Sep 4 17:56:30.007808 kernel: Linux version 6.6.48-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 Wed Sep 4 15:54:07 -00 2024 Sep 4 17:56:30.007851 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=ceda2dd706627da8006bcd6ae77ea155b2a7de6732e2c1c7ab4bed271400663d Sep 4 17:56:30.007864 kernel: BIOS-provided physical RAM map: Sep 4 17:56:30.007872 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 4 17:56:30.007880 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 4 17:56:30.007887 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 4 17:56:30.007896 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable Sep 4 17:56:30.007904 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved Sep 4 17:56:30.007912 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 4 17:56:30.007922 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 4 17:56:30.007930 kernel: NX (Execute Disable) protection: active Sep 4 17:56:30.007938 kernel: APIC: Static calls initialized Sep 4 17:56:30.007946 kernel: SMBIOS 2.8 present. Sep 4 17:56:30.007954 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014 Sep 4 17:56:30.007963 kernel: Hypervisor detected: KVM Sep 4 17:56:30.007974 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 4 17:56:30.007983 kernel: kvm-clock: using sched offset of 4874444437 cycles Sep 4 17:56:30.007992 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 4 17:56:30.008001 kernel: tsc: Detected 1996.249 MHz processor Sep 4 17:56:30.008009 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 17:56:30.008018 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 17:56:30.008047 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000 Sep 4 17:56:30.008056 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 4 17:56:30.008065 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 17:56:30.008076 kernel: ACPI: Early table checksum verification disabled Sep 4 17:56:30.008084 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS ) Sep 4 17:56:30.008092 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:56:30.008101 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:56:30.008109 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:56:30.008117 kernel: ACPI: FACS 0x000000007FFE0000 000040 Sep 4 17:56:30.008126 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:56:30.008134 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 17:56:30.008142 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f] Sep 4 17:56:30.008153 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b] Sep 4 17:56:30.008161 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Sep 4 17:56:30.008169 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f] Sep 4 17:56:30.008177 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847] Sep 4 17:56:30.008185 kernel: No NUMA configuration found Sep 4 17:56:30.008193 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff] Sep 4 17:56:30.008201 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff] Sep 4 17:56:30.008212 kernel: Zone ranges: Sep 4 17:56:30.008222 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 17:56:30.008231 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdcfff] Sep 4 17:56:30.008240 kernel: Normal empty Sep 4 17:56:30.008248 kernel: Movable zone start for each node Sep 4 17:56:30.008257 kernel: Early memory node ranges Sep 4 17:56:30.008265 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 4 17:56:30.008276 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdcfff] Sep 4 17:56:30.008284 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff] Sep 4 17:56:30.008293 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 17:56:30.008301 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 4 17:56:30.008310 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges Sep 4 17:56:30.008318 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 4 17:56:30.008327 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 4 17:56:30.008335 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 4 17:56:30.008344 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 4 17:56:30.008352 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 4 17:56:30.008363 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 17:56:30.008371 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 4 17:56:30.008380 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 4 17:56:30.008388 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 17:56:30.008397 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Sep 4 17:56:30.008405 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 4 17:56:30.008413 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Sep 4 17:56:30.008422 kernel: Booting paravirtualized kernel on KVM Sep 4 17:56:30.008430 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 17:56:30.008441 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 4 17:56:30.008450 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Sep 4 17:56:30.008458 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Sep 4 17:56:30.008467 kernel: pcpu-alloc: [0] 0 1 Sep 4 17:56:30.008475 kernel: kvm-guest: PV spinlocks disabled, no host support Sep 4 17:56:30.008485 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=ceda2dd706627da8006bcd6ae77ea155b2a7de6732e2c1c7ab4bed271400663d Sep 4 17:56:30.008494 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 17:56:30.008503 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 17:56:30.008513 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 17:56:30.008522 kernel: Fallback order for Node 0: 0 Sep 4 17:56:30.008530 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515805 Sep 4 17:56:30.008539 kernel: Policy zone: DMA32 Sep 4 17:56:30.008547 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 17:56:30.008556 kernel: Memory: 1971212K/2096620K available (12288K kernel code, 2304K rwdata, 22708K rodata, 42704K init, 2488K bss, 125148K reserved, 0K cma-reserved) Sep 4 17:56:30.008564 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 4 17:56:30.008573 kernel: ftrace: allocating 37748 entries in 148 pages Sep 4 17:56:30.008584 kernel: ftrace: allocated 148 pages with 3 groups Sep 4 17:56:30.008593 kernel: Dynamic Preempt: voluntary Sep 4 17:56:30.008601 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 17:56:30.008611 kernel: rcu: RCU event tracing is enabled. Sep 4 17:56:30.008620 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 4 17:56:30.008628 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 17:56:30.008637 kernel: Rude variant of Tasks RCU enabled. Sep 4 17:56:30.008646 kernel: Tracing variant of Tasks RCU enabled. Sep 4 17:56:30.008654 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 17:56:30.008663 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 4 17:56:30.008673 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Sep 4 17:56:30.008681 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 17:56:30.008690 kernel: Console: colour VGA+ 80x25 Sep 4 17:56:30.008698 kernel: printk: console [tty0] enabled Sep 4 17:56:30.008707 kernel: printk: console [ttyS0] enabled Sep 4 17:56:30.008715 kernel: ACPI: Core revision 20230628 Sep 4 17:56:30.008724 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 17:56:30.008732 kernel: x2apic enabled Sep 4 17:56:30.008740 kernel: APIC: Switched APIC routing to: physical x2apic Sep 4 17:56:30.008751 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 4 17:56:30.008760 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Sep 4 17:56:30.008768 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Sep 4 17:56:30.008777 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 4 17:56:30.008785 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 4 17:56:30.008794 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 17:56:30.008803 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 17:56:30.008811 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Sep 4 17:56:30.008820 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Sep 4 17:56:30.008830 kernel: Speculative Store Bypass: Vulnerable Sep 4 17:56:30.008839 kernel: x86/fpu: x87 FPU will use FXSAVE Sep 4 17:56:30.008847 kernel: Freeing SMP alternatives memory: 32K Sep 4 17:56:30.008856 kernel: pid_max: default: 32768 minimum: 301 Sep 4 17:56:30.008864 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 17:56:30.008872 kernel: landlock: Up and running. Sep 4 17:56:30.008881 kernel: SELinux: Initializing. Sep 4 17:56:30.008890 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 4 17:56:30.008906 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 4 17:56:30.008915 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Sep 4 17:56:30.008924 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:56:30.008933 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:56:30.008944 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:56:30.008952 kernel: Performance Events: AMD PMU driver. Sep 4 17:56:30.008961 kernel: ... version: 0 Sep 4 17:56:30.008970 kernel: ... bit width: 48 Sep 4 17:56:30.008979 kernel: ... generic registers: 4 Sep 4 17:56:30.008990 kernel: ... value mask: 0000ffffffffffff Sep 4 17:56:30.008998 kernel: ... max period: 00007fffffffffff Sep 4 17:56:30.009007 kernel: ... fixed-purpose events: 0 Sep 4 17:56:30.009016 kernel: ... event mask: 000000000000000f Sep 4 17:56:30.009036 kernel: signal: max sigframe size: 1440 Sep 4 17:56:30.009045 kernel: rcu: Hierarchical SRCU implementation. Sep 4 17:56:30.009071 kernel: rcu: Max phase no-delay instances is 400. Sep 4 17:56:30.009081 kernel: smp: Bringing up secondary CPUs ... Sep 4 17:56:30.009090 kernel: smpboot: x86: Booting SMP configuration: Sep 4 17:56:30.009102 kernel: .... node #0, CPUs: #1 Sep 4 17:56:30.009111 kernel: smp: Brought up 1 node, 2 CPUs Sep 4 17:56:30.009120 kernel: smpboot: Max logical packages: 2 Sep 4 17:56:30.009129 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Sep 4 17:56:30.009138 kernel: devtmpfs: initialized Sep 4 17:56:30.009146 kernel: x86/mm: Memory block size: 128MB Sep 4 17:56:30.009155 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 17:56:30.009164 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 4 17:56:30.009174 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 17:56:30.009184 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 17:56:30.009193 kernel: audit: initializing netlink subsys (disabled) Sep 4 17:56:30.009202 kernel: audit: type=2000 audit(1725472588.596:1): state=initialized audit_enabled=0 res=1 Sep 4 17:56:30.009211 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 17:56:30.009220 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 17:56:30.009228 kernel: cpuidle: using governor menu Sep 4 17:56:30.009238 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 17:56:30.009246 kernel: dca service started, version 1.12.1 Sep 4 17:56:30.009255 kernel: PCI: Using configuration type 1 for base access Sep 4 17:56:30.009266 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 17:56:30.009275 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 17:56:30.009284 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 17:56:30.009293 kernel: ACPI: Added _OSI(Module Device) Sep 4 17:56:30.009302 kernel: ACPI: Added _OSI(Processor Device) Sep 4 17:56:30.009311 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 17:56:30.009320 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 17:56:30.009329 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 17:56:30.009338 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 4 17:56:30.009349 kernel: ACPI: Interpreter enabled Sep 4 17:56:30.009357 kernel: ACPI: PM: (supports S0 S3 S5) Sep 4 17:56:30.009366 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 17:56:30.009375 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 17:56:30.009384 kernel: PCI: Using E820 reservations for host bridge windows Sep 4 17:56:30.009393 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Sep 4 17:56:30.009402 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 17:56:30.009579 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Sep 4 17:56:30.009685 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Sep 4 17:56:30.009777 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Sep 4 17:56:30.009791 kernel: acpiphp: Slot [3] registered Sep 4 17:56:30.009800 kernel: acpiphp: Slot [4] registered Sep 4 17:56:30.009809 kernel: acpiphp: Slot [5] registered Sep 4 17:56:30.009818 kernel: acpiphp: Slot [6] registered Sep 4 17:56:30.009827 kernel: acpiphp: Slot [7] registered Sep 4 17:56:30.009835 kernel: acpiphp: Slot [8] registered Sep 4 17:56:30.009847 kernel: acpiphp: Slot [9] registered Sep 4 17:56:30.009856 kernel: acpiphp: Slot [10] registered Sep 4 17:56:30.009865 kernel: acpiphp: Slot [11] registered Sep 4 17:56:30.009873 kernel: acpiphp: Slot [12] registered Sep 4 17:56:30.009882 kernel: acpiphp: Slot [13] registered Sep 4 17:56:30.009891 kernel: acpiphp: Slot [14] registered Sep 4 17:56:30.009900 kernel: acpiphp: Slot [15] registered Sep 4 17:56:30.009908 kernel: acpiphp: Slot [16] registered Sep 4 17:56:30.009917 kernel: acpiphp: Slot [17] registered Sep 4 17:56:30.009926 kernel: acpiphp: Slot [18] registered Sep 4 17:56:30.009936 kernel: acpiphp: Slot [19] registered Sep 4 17:56:30.009945 kernel: acpiphp: Slot [20] registered Sep 4 17:56:30.009967 kernel: acpiphp: Slot [21] registered Sep 4 17:56:30.009976 kernel: acpiphp: Slot [22] registered Sep 4 17:56:30.009985 kernel: acpiphp: Slot [23] registered Sep 4 17:56:30.009994 kernel: acpiphp: Slot [24] registered Sep 4 17:56:30.010002 kernel: acpiphp: Slot [25] registered Sep 4 17:56:30.010011 kernel: acpiphp: Slot [26] registered Sep 4 17:56:30.010020 kernel: acpiphp: Slot [27] registered Sep 4 17:56:30.010059 kernel: acpiphp: Slot [28] registered Sep 4 17:56:30.010071 kernel: acpiphp: Slot [29] registered Sep 4 17:56:30.010079 kernel: acpiphp: Slot [30] registered Sep 4 17:56:30.010088 kernel: acpiphp: Slot [31] registered Sep 4 17:56:30.010097 kernel: PCI host bridge to bus 0000:00 Sep 4 17:56:30.010200 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 4 17:56:30.010290 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 4 17:56:30.010416 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 4 17:56:30.010506 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Sep 4 17:56:30.010596 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Sep 4 17:56:30.010682 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 17:56:30.010811 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Sep 4 17:56:30.010929 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Sep 4 17:56:30.011056 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Sep 4 17:56:30.011163 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Sep 4 17:56:30.011269 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Sep 4 17:56:30.011368 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Sep 4 17:56:30.011472 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Sep 4 17:56:30.011562 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Sep 4 17:56:30.011661 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Sep 4 17:56:30.011753 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Sep 4 17:56:30.011850 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Sep 4 17:56:30.011949 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Sep 4 17:56:30.012078 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Sep 4 17:56:30.012179 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Sep 4 17:56:30.012272 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Sep 4 17:56:30.012367 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Sep 4 17:56:30.012462 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 4 17:56:30.012584 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Sep 4 17:56:30.012685 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Sep 4 17:56:30.012784 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Sep 4 17:56:30.012882 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Sep 4 17:56:30.012980 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Sep 4 17:56:30.013115 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Sep 4 17:56:30.013217 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Sep 4 17:56:30.013321 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Sep 4 17:56:30.013419 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Sep 4 17:56:30.013527 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Sep 4 17:56:30.013625 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Sep 4 17:56:30.013723 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Sep 4 17:56:30.013830 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Sep 4 17:56:30.013928 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Sep 4 17:56:30.014065 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Sep 4 17:56:30.014081 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 4 17:56:30.014091 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 4 17:56:30.014199 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 4 17:56:30.014214 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 4 17:56:30.014224 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Sep 4 17:56:30.014233 kernel: iommu: Default domain type: Translated Sep 4 17:56:30.014243 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 17:56:30.014257 kernel: PCI: Using ACPI for IRQ routing Sep 4 17:56:30.014266 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 4 17:56:30.014276 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 4 17:56:30.014285 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff] Sep 4 17:56:30.014392 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Sep 4 17:56:30.014491 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Sep 4 17:56:30.014598 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 4 17:56:30.014612 kernel: vgaarb: loaded Sep 4 17:56:30.014622 kernel: clocksource: Switched to clocksource kvm-clock Sep 4 17:56:30.014636 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 17:56:30.014646 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 17:56:30.014655 kernel: pnp: PnP ACPI init Sep 4 17:56:30.014765 kernel: pnp 00:03: [dma 2] Sep 4 17:56:30.014781 kernel: pnp: PnP ACPI: found 5 devices Sep 4 17:56:30.014791 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 17:56:30.014800 kernel: NET: Registered PF_INET protocol family Sep 4 17:56:30.014810 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 17:56:30.014820 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Sep 4 17:56:30.014834 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 17:56:30.014843 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 4 17:56:30.014853 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Sep 4 17:56:30.014863 kernel: TCP: Hash tables configured (established 16384 bind 16384) Sep 4 17:56:30.014872 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 4 17:56:30.014882 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 4 17:56:30.014892 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 17:56:30.014901 kernel: NET: Registered PF_XDP protocol family Sep 4 17:56:30.014994 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 4 17:56:30.015141 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 4 17:56:30.015227 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 4 17:56:30.015312 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Sep 4 17:56:30.015397 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Sep 4 17:56:30.015492 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Sep 4 17:56:30.015584 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Sep 4 17:56:30.015598 kernel: PCI: CLS 0 bytes, default 64 Sep 4 17:56:30.015611 kernel: Initialise system trusted keyrings Sep 4 17:56:30.015620 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Sep 4 17:56:30.015629 kernel: Key type asymmetric registered Sep 4 17:56:30.015638 kernel: Asymmetric key parser 'x509' registered Sep 4 17:56:30.015647 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 4 17:56:30.015656 kernel: io scheduler mq-deadline registered Sep 4 17:56:30.015665 kernel: io scheduler kyber registered Sep 4 17:56:30.015674 kernel: io scheduler bfq registered Sep 4 17:56:30.015683 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 17:56:30.015695 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Sep 4 17:56:30.015704 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Sep 4 17:56:30.015713 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Sep 4 17:56:30.015722 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Sep 4 17:56:30.015741 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 17:56:30.015761 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 17:56:30.015778 kernel: random: crng init done Sep 4 17:56:30.015790 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 4 17:56:30.015803 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 4 17:56:30.015822 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 4 17:56:30.015931 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 4 17:56:30.015947 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 4 17:56:30.016050 kernel: rtc_cmos 00:04: registered as rtc0 Sep 4 17:56:30.016146 kernel: rtc_cmos 00:04: setting system clock to 2024-09-04T17:56:29 UTC (1725472589) Sep 4 17:56:30.016232 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 4 17:56:30.016246 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 4 17:56:30.016257 kernel: NET: Registered PF_INET6 protocol family Sep 4 17:56:30.016272 kernel: Segment Routing with IPv6 Sep 4 17:56:30.016282 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 17:56:30.016292 kernel: NET: Registered PF_PACKET protocol family Sep 4 17:56:30.016302 kernel: Key type dns_resolver registered Sep 4 17:56:30.016312 kernel: IPI shorthand broadcast: enabled Sep 4 17:56:30.016322 kernel: sched_clock: Marking stable (944007377, 127518213)->(1074012280, -2486690) Sep 4 17:56:30.016332 kernel: registered taskstats version 1 Sep 4 17:56:30.016342 kernel: Loading compiled-in X.509 certificates Sep 4 17:56:30.016352 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 8669771ab5e11f458b79e6634fe685dacc266b18' Sep 4 17:56:30.016365 kernel: Key type .fscrypt registered Sep 4 17:56:30.016375 kernel: Key type fscrypt-provisioning registered Sep 4 17:56:30.016385 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 17:56:30.016395 kernel: ima: Allocated hash algorithm: sha1 Sep 4 17:56:30.016405 kernel: ima: No architecture policies found Sep 4 17:56:30.016415 kernel: clk: Disabling unused clocks Sep 4 17:56:30.016425 kernel: Freeing unused kernel image (initmem) memory: 42704K Sep 4 17:56:30.016435 kernel: Write protecting the kernel read-only data: 36864k Sep 4 17:56:30.016445 kernel: Freeing unused kernel image (rodata/data gap) memory: 1868K Sep 4 17:56:30.016457 kernel: Run /init as init process Sep 4 17:56:30.016467 kernel: with arguments: Sep 4 17:56:30.016477 kernel: /init Sep 4 17:56:30.016487 kernel: with environment: Sep 4 17:56:30.016496 kernel: HOME=/ Sep 4 17:56:30.016506 kernel: TERM=linux Sep 4 17:56:30.016516 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 17:56:30.016536 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) Sep 4 17:56:30.016552 systemd[1]: Detected virtualization kvm. Sep 4 17:56:30.016563 systemd[1]: Detected architecture x86-64. Sep 4 17:56:30.016574 systemd[1]: Running in initrd. Sep 4 17:56:30.016585 systemd[1]: No hostname configured, using default hostname. Sep 4 17:56:30.016595 systemd[1]: Hostname set to . Sep 4 17:56:30.016606 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:56:30.016617 systemd[1]: Queued start job for default target initrd.target. Sep 4 17:56:30.016630 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:56:30.016642 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:56:30.016657 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 17:56:30.016673 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:56:30.016683 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 17:56:30.016693 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 17:56:30.016704 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 17:56:30.016718 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 17:56:30.016727 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:56:30.016737 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:56:30.016747 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:56:30.016765 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:56:30.016777 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:56:30.016789 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:56:30.016799 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:56:30.016809 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:56:30.016819 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 17:56:30.016829 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 17:56:30.016839 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:56:30.016849 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:56:30.016859 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:56:30.016869 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:56:30.016881 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 17:56:30.016891 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:56:30.016901 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 17:56:30.016911 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 17:56:30.016921 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:56:30.016931 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:56:30.016960 systemd-journald[184]: Collecting audit messages is disabled. Sep 4 17:56:30.016985 systemd-journald[184]: Journal started Sep 4 17:56:30.017007 systemd-journald[184]: Runtime Journal (/run/log/journal/a6c8ca84a0e246c1ac3ed6b36bc8b9b9) is 4.9M, max 39.3M, 34.4M free. Sep 4 17:56:30.021057 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:30.034447 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:56:30.037252 systemd-modules-load[185]: Inserted module 'overlay' Sep 4 17:56:30.037524 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 17:56:30.040127 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:56:30.042895 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 17:56:30.053151 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 17:56:30.094509 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 17:56:30.094562 kernel: Bridge firewalling registered Sep 4 17:56:30.076255 systemd-modules-load[185]: Inserted module 'br_netfilter' Sep 4 17:56:30.105304 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 17:56:30.106571 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:56:30.107265 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:30.113209 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:56:30.117823 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:56:30.119684 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 17:56:30.121318 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:56:30.132288 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:56:30.135272 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:56:30.139208 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:56:30.147114 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:56:30.150984 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:56:30.152446 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 17:56:30.184344 systemd-resolved[214]: Positive Trust Anchors: Sep 4 17:56:30.184374 systemd-resolved[214]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:56:30.184416 systemd-resolved[214]: 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 Sep 4 17:56:30.187629 systemd-resolved[214]: Defaulting to hostname 'linux'. Sep 4 17:56:30.202416 dracut-cmdline[221]: dracut-dracut-053 Sep 4 17:56:30.202416 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=ceda2dd706627da8006bcd6ae77ea155b2a7de6732e2c1c7ab4bed271400663d Sep 4 17:56:30.188888 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:56:30.200754 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:56:30.281075 kernel: SCSI subsystem initialized Sep 4 17:56:30.292048 kernel: Loading iSCSI transport class v2.0-870. Sep 4 17:56:30.305052 kernel: iscsi: registered transport (tcp) Sep 4 17:56:30.329524 kernel: iscsi: registered transport (qla4xxx) Sep 4 17:56:30.329597 kernel: QLogic iSCSI HBA Driver Sep 4 17:56:30.392624 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 17:56:30.404331 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 17:56:30.461938 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 17:56:30.462109 kernel: device-mapper: uevent: version 1.0.3 Sep 4 17:56:30.464505 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 17:56:30.531128 kernel: raid6: sse2x4 gen() 5126 MB/s Sep 4 17:56:30.549134 kernel: raid6: sse2x2 gen() 6440 MB/s Sep 4 17:56:30.566309 kernel: raid6: sse2x1 gen() 9199 MB/s Sep 4 17:56:30.566381 kernel: raid6: using algorithm sse2x1 gen() 9199 MB/s Sep 4 17:56:30.584667 kernel: raid6: .... xor() 7094 MB/s, rmw enabled Sep 4 17:56:30.584732 kernel: raid6: using ssse3x2 recovery algorithm Sep 4 17:56:30.608194 kernel: xor: measuring software checksum speed Sep 4 17:56:30.608269 kernel: prefetch64-sse : 18520 MB/sec Sep 4 17:56:30.611649 kernel: generic_sse : 16935 MB/sec Sep 4 17:56:30.611746 kernel: xor: using function: prefetch64-sse (18520 MB/sec) Sep 4 17:56:30.801105 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 17:56:30.820111 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:56:30.830388 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:56:30.876418 systemd-udevd[404]: Using default interface naming scheme 'v255'. Sep 4 17:56:30.888128 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:56:30.900626 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 17:56:30.944630 dracut-pre-trigger[412]: rd.md=0: removing MD RAID activation Sep 4 17:56:30.997137 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:56:31.007263 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:56:31.073933 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:56:31.086738 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 17:56:31.138434 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 17:56:31.142247 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:56:31.143887 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:56:31.145734 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:56:31.154418 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 17:56:31.172204 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Sep 4 17:56:31.176143 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:56:31.186853 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) Sep 4 17:56:31.193929 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 17:56:31.194122 kernel: GPT:17805311 != 41943039 Sep 4 17:56:31.194138 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 17:56:31.194160 kernel: GPT:17805311 != 41943039 Sep 4 17:56:31.194172 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 17:56:31.193551 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:56:31.201811 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:56:31.193803 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:56:31.200191 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:56:31.200794 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:56:31.203549 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:31.204303 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:31.217749 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:31.229062 kernel: libata version 3.00 loaded. Sep 4 17:56:31.242425 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (458) Sep 4 17:56:31.243205 kernel: ata_piix 0000:00:01.1: version 2.13 Sep 4 17:56:31.252117 kernel: scsi host0: ata_piix Sep 4 17:56:31.252445 kernel: BTRFS: device fsid 0dc40443-7f77-4fa7-b5e4-579d4bba0772 devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (463) Sep 4 17:56:31.258093 kernel: scsi host1: ata_piix Sep 4 17:56:31.258387 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Sep 4 17:56:31.258403 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Sep 4 17:56:31.281334 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 17:56:31.302837 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:31.311019 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 17:56:31.317223 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 17:56:31.317916 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 4 17:56:31.325558 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:56:31.331192 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 17:56:31.335185 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:56:31.356907 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:56:31.367551 disk-uuid[499]: Primary Header is updated. Sep 4 17:56:31.367551 disk-uuid[499]: Secondary Entries is updated. Sep 4 17:56:31.367551 disk-uuid[499]: Secondary Header is updated. Sep 4 17:56:31.375202 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:56:31.380615 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:56:32.395106 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 17:56:32.397635 disk-uuid[508]: The operation has completed successfully. Sep 4 17:56:32.456963 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 17:56:32.457285 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 17:56:32.508193 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 17:56:32.513591 sh[521]: Success Sep 4 17:56:32.537057 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Sep 4 17:56:32.603360 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 17:56:32.616281 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 17:56:32.621301 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 17:56:32.671149 kernel: BTRFS info (device dm-0): first mount of filesystem 0dc40443-7f77-4fa7-b5e4-579d4bba0772 Sep 4 17:56:32.671264 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 17:56:32.674197 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 17:56:32.677770 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 17:56:32.680622 kernel: BTRFS info (device dm-0): using free space tree Sep 4 17:56:32.701476 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 17:56:32.703752 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 17:56:32.712357 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 17:56:32.724286 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 17:56:32.749081 kernel: BTRFS info (device vda6): first mount of filesystem b2463ce1-c756-4e78-b7f2-401dad24571d Sep 4 17:56:32.749181 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 17:56:32.752738 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:56:32.759122 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:56:32.775351 kernel: BTRFS info (device vda6): last unmount of filesystem b2463ce1-c756-4e78-b7f2-401dad24571d Sep 4 17:56:32.775058 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 17:56:32.786549 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 17:56:32.794561 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 17:56:32.881508 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:56:32.890303 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:56:32.913639 systemd-networkd[703]: lo: Link UP Sep 4 17:56:32.913651 systemd-networkd[703]: lo: Gained carrier Sep 4 17:56:32.915296 systemd-networkd[703]: Enumeration completed Sep 4 17:56:32.916011 systemd-networkd[703]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:56:32.916016 systemd-networkd[703]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:56:32.917346 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:56:32.917377 systemd-networkd[703]: eth0: Link UP Sep 4 17:56:32.917382 systemd-networkd[703]: eth0: Gained carrier Sep 4 17:56:32.917390 systemd-networkd[703]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:56:32.926331 systemd[1]: Reached target network.target - Network. Sep 4 17:56:32.935095 systemd-networkd[703]: eth0: DHCPv4 address 172.24.4.27/24, gateway 172.24.4.1 acquired from 172.24.4.1 Sep 4 17:56:32.944896 ignition[622]: Ignition 2.19.0 Sep 4 17:56:32.944910 ignition[622]: Stage: fetch-offline Sep 4 17:56:32.946941 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:56:32.944950 ignition[622]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:32.944961 ignition[622]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:32.945091 ignition[622]: parsed url from cmdline: "" Sep 4 17:56:32.945095 ignition[622]: no config URL provided Sep 4 17:56:32.945101 ignition[622]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 17:56:32.945111 ignition[622]: no config at "/usr/lib/ignition/user.ign" Sep 4 17:56:32.945116 ignition[622]: failed to fetch config: resource requires networking Sep 4 17:56:32.945295 ignition[622]: Ignition finished successfully Sep 4 17:56:32.954424 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 17:56:32.967349 ignition[712]: Ignition 2.19.0 Sep 4 17:56:32.967362 ignition[712]: Stage: fetch Sep 4 17:56:32.967547 ignition[712]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:32.967559 ignition[712]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:32.967660 ignition[712]: parsed url from cmdline: "" Sep 4 17:56:32.967664 ignition[712]: no config URL provided Sep 4 17:56:32.967669 ignition[712]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 17:56:32.967677 ignition[712]: no config at "/usr/lib/ignition/user.ign" Sep 4 17:56:32.967795 ignition[712]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Sep 4 17:56:32.967887 ignition[712]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Sep 4 17:56:32.967945 ignition[712]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Sep 4 17:56:33.152876 systemd-resolved[214]: Detected conflict on linux IN A 172.24.4.27 Sep 4 17:56:33.152930 systemd-resolved[214]: Hostname conflict, changing published hostname from 'linux' to 'linux9'. Sep 4 17:56:33.175894 ignition[712]: GET result: OK Sep 4 17:56:33.176005 ignition[712]: parsing config with SHA512: 3deabef943117ad6242fe1832fb659df95a32fcd3e99a6646ab1dca84ab5320fe88dc0eaaee223f901fb61cb889fed6c4448421364247dd8c52c654be457bcc3 Sep 4 17:56:33.197488 unknown[712]: fetched base config from "system" Sep 4 17:56:33.197513 unknown[712]: fetched base config from "system" Sep 4 17:56:33.198172 ignition[712]: fetch: fetch complete Sep 4 17:56:33.197528 unknown[712]: fetched user config from "openstack" Sep 4 17:56:33.198185 ignition[712]: fetch: fetch passed Sep 4 17:56:33.201927 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 17:56:33.198273 ignition[712]: Ignition finished successfully Sep 4 17:56:33.213356 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 17:56:33.249820 ignition[718]: Ignition 2.19.0 Sep 4 17:56:33.249856 ignition[718]: Stage: kargs Sep 4 17:56:33.251417 ignition[718]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:33.251448 ignition[718]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:33.253801 ignition[718]: kargs: kargs passed Sep 4 17:56:33.256368 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 17:56:33.253923 ignition[718]: Ignition finished successfully Sep 4 17:56:33.268327 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 17:56:33.285841 ignition[724]: Ignition 2.19.0 Sep 4 17:56:33.285863 ignition[724]: Stage: disks Sep 4 17:56:33.286445 ignition[724]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:33.286473 ignition[724]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:33.288265 ignition[724]: disks: disks passed Sep 4 17:56:33.289903 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 17:56:33.288358 ignition[724]: Ignition finished successfully Sep 4 17:56:33.292817 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 17:56:33.294434 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 17:56:33.296387 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:56:33.298388 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:56:33.300115 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:56:33.315285 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 17:56:33.340667 systemd-fsck[732]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Sep 4 17:56:33.351672 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 17:56:33.359279 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 17:56:33.657078 kernel: EXT4-fs (vda9): mounted filesystem bdbe0f61-2675-40b7-b9ae-5653402e9b23 r/w with ordered data mode. Quota mode: none. Sep 4 17:56:33.659269 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 17:56:33.661507 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 17:56:33.709202 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:56:33.756266 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 17:56:33.765858 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (740) Sep 4 17:56:33.760400 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 17:56:33.785250 kernel: BTRFS info (device vda6): first mount of filesystem b2463ce1-c756-4e78-b7f2-401dad24571d Sep 4 17:56:33.785300 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 17:56:33.785331 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:56:33.785360 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:56:33.768395 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Sep 4 17:56:33.789616 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 17:56:33.789701 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:56:33.798996 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:56:33.800555 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 17:56:33.819485 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 17:56:33.959630 initrd-setup-root[767]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 17:56:33.970891 initrd-setup-root[775]: cut: /sysroot/etc/group: No such file or directory Sep 4 17:56:33.976645 initrd-setup-root[782]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 17:56:33.981430 initrd-setup-root[789]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 17:56:34.071188 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 17:56:34.076101 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 17:56:34.077354 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 17:56:34.086308 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 17:56:34.088831 kernel: BTRFS info (device vda6): last unmount of filesystem b2463ce1-c756-4e78-b7f2-401dad24571d Sep 4 17:56:34.111252 ignition[856]: INFO : Ignition 2.19.0 Sep 4 17:56:34.111252 ignition[856]: INFO : Stage: mount Sep 4 17:56:34.114554 ignition[856]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:34.114554 ignition[856]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:34.114554 ignition[856]: INFO : mount: mount passed Sep 4 17:56:34.114554 ignition[856]: INFO : Ignition finished successfully Sep 4 17:56:34.113528 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 17:56:34.118449 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 17:56:34.789088 systemd-networkd[703]: eth0: Gained IPv6LL Sep 4 17:56:41.059432 coreos-metadata[742]: Sep 04 17:56:41.059 WARN failed to locate config-drive, using the metadata service API instead Sep 4 17:56:41.099731 coreos-metadata[742]: Sep 04 17:56:41.099 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Sep 4 17:56:41.115673 coreos-metadata[742]: Sep 04 17:56:41.115 INFO Fetch successful Sep 4 17:56:41.118280 coreos-metadata[742]: Sep 04 17:56:41.118 INFO wrote hostname ci-4054-1-0-c-963fa584f5.novalocal to /sysroot/etc/hostname Sep 4 17:56:41.120535 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Sep 4 17:56:41.120771 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Sep 4 17:56:41.133240 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 17:56:41.165405 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:56:41.182128 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (874) Sep 4 17:56:41.188250 kernel: BTRFS info (device vda6): first mount of filesystem b2463ce1-c756-4e78-b7f2-401dad24571d Sep 4 17:56:41.188324 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 17:56:41.191418 kernel: BTRFS info (device vda6): using free space tree Sep 4 17:56:41.200074 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 17:56:41.205252 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:56:41.247229 ignition[892]: INFO : Ignition 2.19.0 Sep 4 17:56:41.247229 ignition[892]: INFO : Stage: files Sep 4 17:56:41.250321 ignition[892]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:41.250321 ignition[892]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:41.250321 ignition[892]: DEBUG : files: compiled without relabeling support, skipping Sep 4 17:56:41.255619 ignition[892]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 17:56:41.255619 ignition[892]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 17:56:41.259588 ignition[892]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 17:56:41.259588 ignition[892]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 17:56:41.259588 ignition[892]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 17:56:41.257654 unknown[892]: wrote ssh authorized keys file for user: core Sep 4 17:56:41.267010 ignition[892]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(4): [started] processing unit "flanneld.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(4): op(5): [started] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(4): [finished] processing unit "flanneld.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(6): op(7): [started] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(8): [started] processing unit "coreos-metadata-sshkeys@.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(8): op(9): [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" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(8): op(9): [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" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(8): [finished] processing unit "coreos-metadata-sshkeys@.service" Sep 4 17:56:41.267010 ignition[892]: INFO : files: op(a): [started] processing unit "etcd-member.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(a): op(b): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(a): op(b): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(a): [finished] processing unit "etcd-member.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(c): [started] processing unit "flannel-docker-opts.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(c): op(d): [started] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(c): op(d): [finished] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(c): [finished] processing unit "flannel-docker-opts.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(e): [started] processing unit "docker.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(e): [finished] processing unit "docker.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(f): [started] setting preset to enabled for "coreos-metadata-sshkeys@.service " Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(f): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service " Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(10): [started] setting preset to enabled for "docker.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(10): [finished] setting preset to enabled for "docker.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(11): [started] setting preset to enabled for "etcd-member.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(11): [finished] setting preset to enabled for "etcd-member.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(12): [started] setting preset to enabled for "flanneld.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: op(12): [finished] setting preset to enabled for "flanneld.service" Sep 4 17:56:41.297055 ignition[892]: INFO : files: createResultFile: createFiles: op(13): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:56:41.297055 ignition[892]: INFO : files: createResultFile: createFiles: op(13): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:56:41.297055 ignition[892]: INFO : files: files passed Sep 4 17:56:41.280161 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 17:56:41.318593 ignition[892]: INFO : Ignition finished successfully Sep 4 17:56:41.291167 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 17:56:41.302174 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 17:56:41.306172 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 17:56:41.321324 initrd-setup-root-after-ignition[919]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:56:41.321324 initrd-setup-root-after-ignition[919]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:56:41.306259 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 17:56:41.323627 initrd-setup-root-after-ignition[923]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:56:41.313837 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:56:41.315631 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 17:56:41.326220 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 17:56:41.345732 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 17:56:41.345829 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 17:56:41.347431 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 17:56:41.348319 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 17:56:41.349424 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 17:56:41.355169 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 17:56:41.365397 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:56:41.375164 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 17:56:41.384062 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:56:41.385422 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:56:41.386076 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 17:56:41.387264 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 17:56:41.387383 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:56:41.388547 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 17:56:41.389205 systemd[1]: Stopped target basic.target - Basic System. Sep 4 17:56:41.390255 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 17:56:41.391194 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:56:41.392127 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 17:56:41.393257 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 17:56:41.394332 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:56:41.395416 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 17:56:41.396431 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 17:56:41.397499 systemd[1]: Stopped target swap.target - Swaps. Sep 4 17:56:41.398538 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 17:56:41.398649 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:56:41.399763 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:56:41.400470 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:56:41.401370 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 17:56:41.401475 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:56:41.402405 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 17:56:41.402513 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 17:56:41.403905 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 17:56:41.404049 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:56:41.405141 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 17:56:41.405246 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 17:56:41.416466 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 17:56:41.416964 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 17:56:41.417153 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:56:41.420280 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 17:56:41.420762 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 17:56:41.420938 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:56:41.422155 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 17:56:41.423170 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:56:41.434345 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 17:56:41.434450 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 17:56:41.439078 ignition[945]: INFO : Ignition 2.19.0 Sep 4 17:56:41.439078 ignition[945]: INFO : Stage: umount Sep 4 17:56:41.439078 ignition[945]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:56:41.439078 ignition[945]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Sep 4 17:56:41.439078 ignition[945]: INFO : umount: umount passed Sep 4 17:56:41.439078 ignition[945]: INFO : Ignition finished successfully Sep 4 17:56:41.442768 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 17:56:41.442860 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 17:56:41.444444 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 17:56:41.444514 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 17:56:41.446803 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 17:56:41.446848 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 17:56:41.447367 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 17:56:41.447404 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 17:56:41.447902 systemd[1]: Stopped target network.target - Network. Sep 4 17:56:41.450142 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 17:56:41.450192 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:56:41.451511 systemd[1]: Stopped target paths.target - Path Units. Sep 4 17:56:41.452576 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 17:56:41.456125 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:56:41.456821 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 17:56:41.457284 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 17:56:41.458474 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 17:56:41.458510 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:56:41.459615 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 17:56:41.459646 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:56:41.460500 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 17:56:41.460541 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 17:56:41.461413 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 17:56:41.461452 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 17:56:41.463600 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 17:56:41.465606 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 17:56:41.468421 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 17:56:41.468945 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 17:56:41.469058 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 17:56:41.469135 systemd-networkd[703]: eth0: DHCPv6 lease lost Sep 4 17:56:41.470760 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 17:56:41.471527 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 17:56:41.474616 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 17:56:41.474740 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 17:56:41.476587 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 17:56:41.476886 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:56:41.477444 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 17:56:41.477488 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 17:56:41.484171 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 17:56:41.484896 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 17:56:41.484947 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:56:41.485488 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 17:56:41.485529 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:56:41.486102 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 17:56:41.486143 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 17:56:41.487179 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 17:56:41.487219 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:56:41.488232 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:56:41.499459 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 17:56:41.500176 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:56:41.500914 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 17:56:41.501728 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 17:56:41.503045 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 17:56:41.503090 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 17:56:41.504204 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 17:56:41.504235 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:56:41.505153 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 17:56:41.505193 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:56:41.506674 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 17:56:41.506713 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 17:56:41.507741 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:56:41.507782 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:56:41.518215 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 17:56:41.519470 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 17:56:41.519526 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:56:41.520850 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:56:41.520894 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:41.522881 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 17:56:41.522987 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 17:56:41.524624 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 17:56:41.531482 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 17:56:41.537879 systemd[1]: Switching root. Sep 4 17:56:41.564910 systemd-journald[184]: Journal stopped Sep 4 17:56:42.717601 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). Sep 4 17:56:42.717665 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 17:56:42.717688 kernel: SELinux: policy capability open_perms=1 Sep 4 17:56:42.717699 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 17:56:42.717711 kernel: SELinux: policy capability always_check_network=0 Sep 4 17:56:42.717722 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 17:56:42.717733 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 17:56:42.717747 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 17:56:42.717758 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 17:56:42.717769 kernel: audit: type=1403 audit(1725472601.719:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 17:56:42.717787 systemd[1]: Successfully loaded SELinux policy in 53.133ms. Sep 4 17:56:42.717807 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 11.373ms. Sep 4 17:56:42.717822 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) Sep 4 17:56:42.717835 systemd[1]: Detected virtualization kvm. Sep 4 17:56:42.717850 systemd[1]: Detected architecture x86-64. Sep 4 17:56:42.717863 systemd[1]: Detected first boot. Sep 4 17:56:42.717880 systemd[1]: Hostname set to . Sep 4 17:56:42.717916 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:56:42.717931 zram_generator::config[987]: No configuration found. Sep 4 17:56:42.717945 systemd[1]: Populated /etc with preset unit settings. Sep 4 17:56:42.717959 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 17:56:42.717971 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 17:56:42.717988 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 17:56:42.718002 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 17:56:42.718015 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 17:56:42.720013 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 4 17:56:42.720094 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 17:56:42.720110 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 17:56:42.720124 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 17:56:42.720137 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 17:56:42.720155 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 17:56:42.720177 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 17:56:42.720190 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:56:42.720203 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:56:42.720215 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 17:56:42.720228 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 17:56:42.720241 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 17:56:42.720254 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:56:42.720267 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 17:56:42.720282 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:56:42.720295 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 17:56:42.720307 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 17:56:42.720320 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 17:56:42.720333 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 17:56:42.720346 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:56:42.720360 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:56:42.720373 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:56:42.720386 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:56:42.720399 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 17:56:42.720411 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 17:56:42.720424 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:56:42.720437 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:56:42.720449 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:56:42.720462 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 17:56:42.720475 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 17:56:42.720490 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 17:56:42.720503 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 17:56:42.720516 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:42.720529 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 17:56:42.720542 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 17:56:42.720554 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 17:56:42.720567 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). Sep 4 17:56:42.720581 systemd[1]: Reached target machines.target - Containers. Sep 4 17:56:42.720595 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 17:56:42.720609 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:56:42.720622 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:56:42.720635 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 17:56:42.720648 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:56:42.720660 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:56:42.720673 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:56:42.720686 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 17:56:42.720704 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:56:42.720719 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 17:56:42.720732 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 17:56:42.720745 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 17:56:42.720757 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 17:56:42.720769 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 17:56:42.720782 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:56:42.720794 kernel: loop: module loaded Sep 4 17:56:42.720806 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:56:42.720819 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 17:56:42.720835 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 17:56:42.720847 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:56:42.720861 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 17:56:42.720874 systemd[1]: Stopped verity-setup.service. Sep 4 17:56:42.720888 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:42.720900 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 17:56:42.720913 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 17:56:42.720925 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 17:56:42.720939 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 17:56:42.720951 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 17:56:42.720963 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 17:56:42.720975 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 17:56:42.721011 systemd-journald[1079]: Collecting audit messages is disabled. Sep 4 17:56:42.723648 kernel: ACPI: bus type drm_connector registered Sep 4 17:56:42.723668 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:56:42.723681 kernel: fuse: init (API version 7.39) Sep 4 17:56:42.723694 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 17:56:42.723707 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 17:56:42.723720 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:56:42.723733 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:56:42.723752 systemd-journald[1079]: Journal started Sep 4 17:56:42.723778 systemd-journald[1079]: Runtime Journal (/run/log/journal/a6c8ca84a0e246c1ac3ed6b36bc8b9b9) is 4.9M, max 39.3M, 34.4M free. Sep 4 17:56:42.386813 systemd[1]: Queued start job for default target multi-user.target. Sep 4 17:56:42.725435 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:56:42.414631 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 17:56:42.415055 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 17:56:42.726744 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:56:42.726876 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:56:42.727755 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:56:42.727864 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:56:42.728686 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 17:56:42.728794 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 17:56:42.729620 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:56:42.729734 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:56:42.730518 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:56:42.731327 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 17:56:42.732095 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 17:56:42.741828 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 17:56:42.752109 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 17:56:42.764107 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 17:56:42.764682 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 17:56:42.764720 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:56:42.768159 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 17:56:42.772199 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 17:56:42.774512 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 17:56:42.775689 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:56:42.781223 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 17:56:42.785138 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 17:56:42.785759 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:56:42.787255 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 17:56:42.788529 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:56:42.792655 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:56:42.800253 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 17:56:42.805278 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 17:56:42.808914 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:56:42.811259 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 17:56:42.811882 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 17:56:42.812637 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 17:56:42.817490 systemd-journald[1079]: Time spent on flushing to /var/log/journal/a6c8ca84a0e246c1ac3ed6b36bc8b9b9 is 51.723ms for 946 entries. Sep 4 17:56:42.817490 systemd-journald[1079]: System Journal (/var/log/journal/a6c8ca84a0e246c1ac3ed6b36bc8b9b9) is 8.0M, max 584.8M, 576.8M free. Sep 4 17:56:42.895310 systemd-journald[1079]: Received client request to flush runtime journal. Sep 4 17:56:42.895362 kernel: loop0: detected capacity change from 0 to 140728 Sep 4 17:56:42.824224 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 17:56:42.838121 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 17:56:42.839091 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 17:56:42.851340 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 17:56:42.854082 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:56:42.868916 udevadm[1125]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 17:56:42.898091 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 17:56:43.012453 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 17:56:43.013481 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 17:56:43.120161 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 17:56:43.184137 kernel: loop1: detected capacity change from 0 to 89336 Sep 4 17:56:43.220891 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 17:56:43.235339 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:56:43.441725 systemd-tmpfiles[1140]: ACLs are not supported, ignoring. Sep 4 17:56:43.442579 systemd-tmpfiles[1140]: ACLs are not supported, ignoring. Sep 4 17:56:43.461207 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:56:43.527155 kernel: loop2: detected capacity change from 0 to 8 Sep 4 17:56:43.565372 kernel: loop3: detected capacity change from 0 to 140728 Sep 4 17:56:43.611070 kernel: loop4: detected capacity change from 0 to 89336 Sep 4 17:56:43.671628 kernel: loop5: detected capacity change from 0 to 8 Sep 4 17:56:43.672991 (sd-merge)[1145]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'. Sep 4 17:56:43.673418 (sd-merge)[1145]: Merged extensions into '/usr'. Sep 4 17:56:43.679006 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 17:56:43.689216 systemd[1]: Starting ensure-sysext.service... Sep 4 17:56:43.702520 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 17:56:43.721599 systemd[1]: Reloading requested from client PID 1147 ('systemctl') (unit ensure-sysext.service)... Sep 4 17:56:43.721618 systemd[1]: Reloading... Sep 4 17:56:43.739313 systemd-tmpfiles[1148]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 17:56:43.739986 systemd-tmpfiles[1148]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 17:56:43.741980 systemd-tmpfiles[1148]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 17:56:43.743388 systemd-tmpfiles[1148]: ACLs are not supported, ignoring. Sep 4 17:56:43.743538 systemd-tmpfiles[1148]: ACLs are not supported, ignoring. Sep 4 17:56:43.750605 systemd-tmpfiles[1148]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:56:43.751287 systemd-tmpfiles[1148]: Skipping /boot Sep 4 17:56:43.767487 systemd-tmpfiles[1148]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:56:43.767503 systemd-tmpfiles[1148]: Skipping /boot Sep 4 17:56:43.806091 zram_generator::config[1171]: No configuration found. Sep 4 17:56:44.045122 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. Sep 4 17:56:44.104237 systemd[1]: Reloading finished in 382 ms. Sep 4 17:56:44.141538 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 17:56:44.159345 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 17:56:44.177345 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 17:56:44.183750 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 17:56:44.188254 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:56:44.197786 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 17:56:44.203308 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.203498 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:56:44.216314 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:56:44.220272 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:56:44.224846 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:56:44.226006 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:56:44.227292 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.229640 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 17:56:44.239757 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.240309 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:56:44.241102 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:56:44.252119 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:56:44.254881 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.257135 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:56:44.257277 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:56:44.258425 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:56:44.258541 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:56:44.260965 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:56:44.261831 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:56:44.274303 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 17:56:44.280486 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 17:56:44.294798 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.295149 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:56:44.300362 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:56:44.303264 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:56:44.312369 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:56:44.314267 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:56:44.315425 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:56:44.318671 augenrules[1264]: No rules Sep 4 17:56:44.326474 systemd-udevd[1249]: Using default interface naming scheme 'v255'. Sep 4 17:56:44.329596 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 17:56:44.334234 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 17:56:44.337820 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 17:56:44.339573 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:56:44.340305 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:56:44.341702 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:56:44.342278 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:56:44.343574 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:56:44.344637 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:56:44.349969 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:56:44.350743 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:56:44.354078 systemd[1]: Finished ensure-sysext.service. Sep 4 17:56:44.360979 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:56:44.361115 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:56:44.369355 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 17:56:44.371705 ldconfig[1114]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 17:56:44.384822 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 17:56:44.392314 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 17:56:44.396319 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 17:56:44.401076 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 17:56:44.402299 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). Sep 4 17:56:44.406299 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:56:44.413219 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:56:44.415380 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 17:56:44.494776 systemd-resolved[1235]: Positive Trust Anchors: Sep 4 17:56:44.494795 systemd-resolved[1235]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:56:44.494839 systemd-resolved[1235]: 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 Sep 4 17:56:44.507115 systemd-resolved[1235]: Using system hostname 'ci-4054-1-0-c-963fa584f5.novalocal'. Sep 4 17:56:44.510835 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:56:44.511605 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:56:44.529965 systemd-networkd[1287]: lo: Link UP Sep 4 17:56:44.529974 systemd-networkd[1287]: lo: Gained carrier Sep 4 17:56:44.530534 systemd-networkd[1287]: Enumeration completed Sep 4 17:56:44.530618 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:56:44.531385 systemd[1]: Reached target network.target - Network. Sep 4 17:56:44.537483 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 17:56:44.538533 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 17:56:44.541706 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 17:56:44.555350 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 17:56:44.560108 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1294) Sep 4 17:56:44.574140 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1294) Sep 4 17:56:44.589161 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1289) Sep 4 17:56:44.601293 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 17:56:44.620769 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 17:56:44.644527 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 17:56:44.665256 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 4 17:56:44.670311 systemd-networkd[1287]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:56:44.670319 systemd-networkd[1287]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:56:44.670978 systemd-networkd[1287]: eth0: Link UP Sep 4 17:56:44.670983 systemd-networkd[1287]: eth0: Gained carrier Sep 4 17:56:44.670996 systemd-networkd[1287]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:56:44.676046 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 4 17:56:44.676091 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Sep 4 17:56:44.684215 systemd-networkd[1287]: eth0: DHCPv4 address 172.24.4.27/24, gateway 172.24.4.1 acquired from 172.24.4.1 Sep 4 17:56:44.686203 systemd-timesyncd[1277]: Network configuration changed, trying to establish connection. Sep 4 17:56:44.687263 kernel: ACPI: button: Power Button [PWRF] Sep 4 17:56:44.708206 systemd-timesyncd[1277]: Contacted time server 51.15.191.239:123 (0.flatcar.pool.ntp.org). Sep 4 17:56:44.708297 systemd-timesyncd[1277]: Initial clock synchronization to Wed 2024-09-04 17:56:44.640654 UTC. Sep 4 17:56:44.715054 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 17:56:44.724046 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Sep 4 17:56:44.726052 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Sep 4 17:56:44.728346 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:44.731064 kernel: Console: switching to colour dummy device 80x25 Sep 4 17:56:44.731115 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Sep 4 17:56:44.731131 kernel: [drm] features: -context_init Sep 4 17:56:44.737067 kernel: [drm] number of scanouts: 1 Sep 4 17:56:44.737118 kernel: [drm] number of cap sets: 0 Sep 4 17:56:44.739084 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Sep 4 17:56:44.740257 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:56:44.740417 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:44.747211 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:44.748133 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Sep 4 17:56:44.748170 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 17:56:44.755330 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Sep 4 17:56:44.763305 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:56:44.763765 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:44.772164 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:56:44.772518 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 17:56:44.777204 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 17:56:44.798952 lvm[1329]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:56:44.824629 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 17:56:44.826590 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:56:44.835573 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 17:56:44.845053 lvm[1333]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:56:44.849805 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:56:44.851496 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:56:44.851737 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 17:56:44.851888 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 17:56:44.852774 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 17:56:44.853692 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 17:56:44.853899 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 17:56:44.854047 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 17:56:44.854076 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:56:44.854182 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:56:44.856201 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 17:56:44.857561 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 17:56:44.863627 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 17:56:44.864259 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 17:56:44.864416 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:56:44.869140 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:56:44.869824 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:56:44.869872 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:56:44.875109 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 17:56:44.879268 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 4 17:56:44.885190 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 17:56:44.892201 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 17:56:44.894676 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 17:56:44.899163 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 17:56:44.899714 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). Sep 4 17:56:44.905186 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 17:56:44.909233 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 17:56:44.913605 jq[1345]: false Sep 4 17:56:44.920301 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 17:56:44.921993 coreos-metadata[1340]: Sep 04 17:56:44.921 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Sep 4 17:56:44.928212 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 17:56:44.930855 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 17:56:44.932241 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 17:56:44.941687 coreos-metadata[1340]: Sep 04 17:56:44.941 INFO Fetch successful Sep 4 17:56:44.941687 coreos-metadata[1340]: Sep 04 17:56:44.941 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Sep 4 17:56:44.943206 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 17:56:44.949401 coreos-metadata[1341]: Sep 04 17:56:44.948 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Sep 4 17:56:44.961947 coreos-metadata[1340]: Sep 04 17:56:44.961 INFO Fetch successful Sep 4 17:56:44.961328 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 17:56:44.964347 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 17:56:44.968288 coreos-metadata[1341]: Sep 04 17:56:44.968 INFO Fetch successful Sep 4 17:56:44.968288 coreos-metadata[1341]: Sep 04 17:56:44.968 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Sep 4 17:56:44.971325 unknown[1340]: wrote ssh authorized keys file for user: core Sep 4 17:56:44.975392 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 17:56:44.977092 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 17:56:44.986246 coreos-metadata[1341]: Sep 04 17:56:44.985 INFO Fetch successful Sep 4 17:56:44.986246 coreos-metadata[1341]: Sep 04 17:56:44.986 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Sep 4 17:56:44.994054 jq[1354]: true Sep 4 17:56:45.004645 coreos-metadata[1341]: Sep 04 17:56:45.000 INFO Fetch successful Sep 4 17:56:45.004645 coreos-metadata[1341]: Sep 04 17:56:45.000 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Sep 4 17:56:45.009867 dbus-daemon[1344]: [system] SELinux support is enabled Sep 4 17:56:45.010077 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 17:56:45.017448 coreos-metadata[1341]: Sep 04 17:56:45.017 INFO Fetch successful Sep 4 17:56:45.017604 coreos-metadata[1341]: Sep 04 17:56:45.017 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Sep 4 17:56:45.017935 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). Sep 4 17:56:45.017973 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 17:56:45.021813 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). Sep 4 17:56:45.021846 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 17:56:45.022736 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 17:56:45.024421 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 17:56:45.031047 update_engine[1353]: I0904 17:56:45.030467 1353 main.cc:92] Flatcar Update Engine starting Sep 4 17:56:45.037730 update_engine[1353]: I0904 17:56:45.037485 1353 update_check_scheduler.cc:74] Next update check in 6m48s Sep 4 17:56:45.037764 coreos-metadata[1341]: Sep 04 17:56:45.034 INFO Fetch successful Sep 4 17:56:45.037764 coreos-metadata[1341]: Sep 04 17:56:45.034 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Sep 4 17:56:45.041155 systemd[1]: Started update-engine.service - Update Engine. Sep 4 17:56:45.044217 extend-filesystems[1346]: Found loop3 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found loop4 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found loop5 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found vda Sep 4 17:56:45.044217 extend-filesystems[1346]: Found vda1 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found vda2 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found vda3 Sep 4 17:56:45.044217 extend-filesystems[1346]: Found usr Sep 4 17:56:45.112063 update-ssh-keys[1360]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:56:45.045489 (ntainerd)[1369]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 17:56:45.118538 extend-filesystems[1346]: Found vda4 Sep 4 17:56:45.118538 extend-filesystems[1346]: Found vda6 Sep 4 17:56:45.118538 extend-filesystems[1346]: Found vda7 Sep 4 17:56:45.118538 extend-filesystems[1346]: Found vda9 Sep 4 17:56:45.118538 extend-filesystems[1346]: Checking size of /dev/vda9 Sep 4 17:56:45.158766 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks Sep 4 17:56:45.158859 coreos-metadata[1341]: Sep 04 17:56:45.051 INFO Fetch successful Sep 4 17:56:45.046634 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 4 17:56:45.160930 extend-filesystems[1346]: Resized partition /dev/vda9 Sep 4 17:56:45.060545 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 17:56:45.183467 extend-filesystems[1387]: resize2fs 1.47.1 (20-May-2024) Sep 4 17:56:45.186851 jq[1366]: true Sep 4 17:56:45.079213 systemd-logind[1352]: New seat seat0. Sep 4 17:56:45.102476 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 17:56:45.102679 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 17:56:45.103436 systemd-logind[1352]: Watching system buttons on /dev/input/event1 (Power Button) Sep 4 17:56:45.103455 systemd-logind[1352]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 17:56:45.105880 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 17:56:45.154471 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 17:56:45.156866 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 17:56:45.242803 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1298) Sep 4 17:56:45.292579 locksmithd[1378]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 17:56:45.358505 kernel: EXT4-fs (vda9): resized filesystem to 4635643 Sep 4 17:56:45.453111 extend-filesystems[1387]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 4 17:56:45.453111 extend-filesystems[1387]: old_desc_blocks = 1, new_desc_blocks = 3 Sep 4 17:56:45.453111 extend-filesystems[1387]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long. Sep 4 17:56:45.462129 extend-filesystems[1346]: Resized filesystem in /dev/vda9 Sep 4 17:56:45.462972 bash[1401]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:56:45.454548 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 17:56:45.454769 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 17:56:45.463372 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 17:56:45.477403 systemd[1]: Starting sshkeys.service... Sep 4 17:56:45.500859 systemd[1]: Finished sshkeys.service. Sep 4 17:56:45.536203 containerd[1369]: time="2024-09-04T17:56:45.536063683Z" level=info msg="starting containerd" revision=8ccfc03e4e2b73c22899202ae09d0caf906d3863 version=v1.7.20 Sep 4 17:56:45.585283 containerd[1369]: time="2024-09-04T17:56:45.585060088Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.591671 containerd[1369]: time="2024-09-04T17:56:45.591637256Z" 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.48-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.591744874Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.591768220Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.591933388Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.591953674Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.592041205Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592128 containerd[1369]: time="2024-09-04T17:56:45.592059256Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592425 containerd[1369]: time="2024-09-04T17:56:45.592402933Z" 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 Sep 4 17:56:45.592481 containerd[1369]: time="2024-09-04T17:56:45.592468718Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592540 containerd[1369]: time="2024-09-04T17:56:45.592526138Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592598 containerd[1369]: time="2024-09-04T17:56:45.592584791Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.592729 containerd[1369]: time="2024-09-04T17:56:45.592712248Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.593004 containerd[1369]: time="2024-09-04T17:56:45.592985163Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:56:45.593194 containerd[1369]: time="2024-09-04T17:56:45.593173796Z" 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 Sep 4 17:56:45.593257 containerd[1369]: time="2024-09-04T17:56:45.593243276Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 17:56:45.593395 containerd[1369]: time="2024-09-04T17:56:45.593376217Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 17:56:45.593515 containerd[1369]: time="2024-09-04T17:56:45.593498101Z" level=info msg="metadata content store policy set" policy=shared Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603280752Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603328973Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603348534Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603367160Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603383085Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603528444Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603781749Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603885255Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603906058Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603921495Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603937311Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603951725Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603966011Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605036 containerd[1369]: time="2024-09-04T17:56:45.603981191Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.603997294Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604011510Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604052579Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604075835Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604102965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604122675Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604137159Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604152170Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604165968Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604180085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604192811Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604211606Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604226210Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605364 containerd[1369]: time="2024-09-04T17:56:45.604241420Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604254622Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604268550Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604282598Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604300141Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604321529Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604344965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604358376Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604401968Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604422214Z" 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 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604435128Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604448689Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604460869Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604474151Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 17:56:45.605643 containerd[1369]: time="2024-09-04T17:56:45.604486201Z" level=info msg="NRI interface is disabled by configuration." Sep 4 17:56:45.605924 containerd[1369]: time="2024-09-04T17:56:45.604497189Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 17:56:45.606623 containerd[1369]: time="2024-09-04T17:56:45.604987585Z" 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}" Sep 4 17:56:45.606748 containerd[1369]: time="2024-09-04T17:56:45.606647519Z" level=info msg="Connect containerd service" Sep 4 17:56:45.606748 containerd[1369]: time="2024-09-04T17:56:45.606705704Z" level=info msg="using legacy CRI server" Sep 4 17:56:45.606748 containerd[1369]: time="2024-09-04T17:56:45.606715550Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 17:56:45.606837 containerd[1369]: time="2024-09-04T17:56:45.606814842Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 17:56:45.608354 containerd[1369]: time="2024-09-04T17:56:45.608294548Z" 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" Sep 4 17:56:45.610446 containerd[1369]: time="2024-09-04T17:56:45.610400075Z" level=info msg="Start subscribing containerd event" Sep 4 17:56:45.610541 containerd[1369]: time="2024-09-04T17:56:45.610526876Z" level=info msg="Start recovering state" Sep 4 17:56:45.610690 containerd[1369]: time="2024-09-04T17:56:45.610409989Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 17:56:45.610819 containerd[1369]: time="2024-09-04T17:56:45.610802225Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 17:56:45.613070 containerd[1369]: time="2024-09-04T17:56:45.613051869Z" level=info msg="Start event monitor" Sep 4 17:56:45.613135 containerd[1369]: time="2024-09-04T17:56:45.613122105Z" level=info msg="Start snapshots syncer" Sep 4 17:56:45.613202 containerd[1369]: time="2024-09-04T17:56:45.613188019Z" level=info msg="Start cni network conf syncer for default" Sep 4 17:56:45.613255 containerd[1369]: time="2024-09-04T17:56:45.613243174Z" level=info msg="Start streaming server" Sep 4 17:56:45.613418 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 17:56:45.615589 containerd[1369]: time="2024-09-04T17:56:45.615559200Z" level=info msg="containerd successfully booted in 0.081609s" Sep 4 17:56:46.500445 systemd-networkd[1287]: eth0: Gained IPv6LL Sep 4 17:56:46.503906 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 17:56:46.511087 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 17:56:46.522484 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 4 17:56:46.532542 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 17:56:46.543615 (-wrapper)[1425]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 4 17:56:46.564975 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 17:56:46.764295 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 17:56:46.764819 (dockerd)[1442]: 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 Sep 4 17:56:46.803382 sshd_keygen[1375]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 17:56:46.831473 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 17:56:46.840771 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 17:56:46.848952 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 17:56:46.849298 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 17:56:46.864996 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 17:56:46.884524 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 17:56:46.898727 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 17:56:46.909734 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 17:56:46.916474 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 17:56:47.556649 dockerd[1442]: time="2024-09-04T17:56:47.556100115Z" level=info msg="Starting up" Sep 4 17:56:47.914257 dockerd[1442]: time="2024-09-04T17:56:47.914201903Z" level=info msg="Loading containers: start." Sep 4 17:56:48.123178 kernel: Initializing XFRM netlink socket Sep 4 17:56:48.329750 systemd-networkd[1287]: docker0: Link UP Sep 4 17:56:48.367797 dockerd[1442]: time="2024-09-04T17:56:48.367687247Z" level=info msg="Loading containers: done." Sep 4 17:56:48.404690 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4208001283-merged.mount: Deactivated successfully. Sep 4 17:56:48.408094 dockerd[1442]: time="2024-09-04T17:56:48.407383381Z" 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 Sep 4 17:56:48.408094 dockerd[1442]: time="2024-09-04T17:56:48.407567204Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Sep 4 17:56:48.408094 dockerd[1442]: time="2024-09-04T17:56:48.407824059Z" level=info msg="Daemon has completed initialization" Sep 4 17:56:48.571862 dockerd[1442]: time="2024-09-04T17:56:48.571671345Z" level=info msg="API listen on /run/docker.sock" Sep 4 17:56:48.573569 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 17:56:48.575201 etcd-wrapper[1435]: Error response from daemon: No such container: etcd-member Sep 4 17:56:48.613109 etcd-wrapper[1583]: Error response from daemon: No such container: etcd-member Sep 4 17:56:48.674671 etcd-wrapper[1598]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally Sep 4 17:56:50.332254 etcd-wrapper[1598]: v3.5.0: Pulling from coreos/etcd Sep 4 17:56:50.513200 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 17:56:50.531750 systemd[1]: Started sshd@0-172.24.4.27:22-172.24.4.1:34990.service - OpenSSH per-connection server daemon (172.24.4.1:34990). Sep 4 17:56:50.709951 etcd-wrapper[1598]: 1813d21adc01: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 6e96907ab677: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 444ed0ea8673: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 0fd2df5633f0: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 8cc22b9456bb: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 7ac70aecd290: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 4b376c64dfe4: Pulling fs layer Sep 4 17:56:50.709951 etcd-wrapper[1598]: 0fd2df5633f0: Waiting Sep 4 17:56:50.709951 etcd-wrapper[1598]: 8cc22b9456bb: Waiting Sep 4 17:56:50.710814 etcd-wrapper[1598]: 4b376c64dfe4: Waiting Sep 4 17:56:51.213548 etcd-wrapper[1598]: 444ed0ea8673: Verifying Checksum Sep 4 17:56:51.213548 etcd-wrapper[1598]: 444ed0ea8673: Download complete Sep 4 17:56:51.214104 etcd-wrapper[1598]: 6e96907ab677: Verifying Checksum Sep 4 17:56:51.214172 etcd-wrapper[1598]: 6e96907ab677: Download complete Sep 4 17:56:51.360192 etcd-wrapper[1598]: 1813d21adc01: Verifying Checksum Sep 4 17:56:51.360192 etcd-wrapper[1598]: 1813d21adc01: Download complete Sep 4 17:56:51.596383 etcd-wrapper[1598]: 8cc22b9456bb: Verifying Checksum Sep 4 17:56:51.596512 etcd-wrapper[1598]: 8cc22b9456bb: Download complete Sep 4 17:56:51.655274 etcd-wrapper[1598]: 0fd2df5633f0: Verifying Checksum Sep 4 17:56:51.655274 etcd-wrapper[1598]: 0fd2df5633f0: Download complete Sep 4 17:56:51.759749 etcd-wrapper[1598]: 7ac70aecd290: Verifying Checksum Sep 4 17:56:51.759749 etcd-wrapper[1598]: 7ac70aecd290: Download complete Sep 4 17:56:51.809289 sshd[1606]: Accepted publickey for core from 172.24.4.1 port 34990 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:56:51.812582 sshd[1606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:56:51.822237 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 17:56:51.833430 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 17:56:51.838578 systemd-logind[1352]: New session 1 of user core. Sep 4 17:56:51.850539 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 17:56:51.861577 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 17:56:51.869872 (systemd)[1618]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:56:51.939169 login[1458]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 17:56:51.946886 systemd-logind[1352]: New session 2 of user core. Sep 4 17:56:51.963111 login[1457]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 17:56:51.971351 etcd-wrapper[1598]: 4b376c64dfe4: Verifying Checksum Sep 4 17:56:51.971351 etcd-wrapper[1598]: 4b376c64dfe4: Download complete Sep 4 17:56:51.974371 systemd-logind[1352]: New session 3 of user core. Sep 4 17:56:52.025669 systemd[1618]: Queued start job for default target default.target. Sep 4 17:56:52.030213 systemd[1618]: Created slice app.slice - User Application Slice. Sep 4 17:56:52.030242 systemd[1618]: Reached target paths.target - Paths. Sep 4 17:56:52.030257 systemd[1618]: Reached target timers.target - Timers. Sep 4 17:56:52.031575 systemd[1618]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 17:56:52.053090 systemd[1618]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 17:56:52.053212 systemd[1618]: Reached target sockets.target - Sockets. Sep 4 17:56:52.053230 systemd[1618]: Reached target basic.target - Basic System. Sep 4 17:56:52.053316 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 17:56:52.054312 systemd[1618]: Reached target default.target - Main User Target. Sep 4 17:56:52.054351 systemd[1618]: Startup finished in 174ms. Sep 4 17:56:52.057253 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 17:56:52.058331 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 17:56:52.059254 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 17:56:52.518835 etcd-wrapper[1598]: 1813d21adc01: Pull complete Sep 4 17:56:52.598566 systemd[1]: Started sshd@1-172.24.4.27:22-172.24.4.1:35000.service - OpenSSH per-connection server daemon (172.24.4.1:35000). Sep 4 17:56:52.790700 systemd[1]: var-lib-docker-overlay2-b969a2945dcbe82683070734ba664d7c2e712b90aa32b3a928f6ec4639a9c8dc-merged.mount: Deactivated successfully. Sep 4 17:56:53.408158 etcd-wrapper[1598]: 6e96907ab677: Pull complete Sep 4 17:56:53.649605 systemd[1]: var-lib-docker-overlay2-d46e327afcdc0097676de3d584c6f37fe172c39d85ac2acc89496068ac83c04a-merged.mount: Deactivated successfully. Sep 4 17:56:53.925060 sshd[1657]: Accepted publickey for core from 172.24.4.1 port 35000 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:56:53.927584 sshd[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:56:53.936191 systemd-logind[1352]: New session 4 of user core. Sep 4 17:56:53.944306 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 17:56:54.123157 etcd-wrapper[1598]: 444ed0ea8673: Pull complete Sep 4 17:56:54.334587 systemd[1]: var-lib-docker-overlay2-0dc18fe4d968df4768a211367b8b24a7e29b391a962689e6a9a3d39abd68678f-merged.mount: Deactivated successfully. Sep 4 17:56:54.444201 etcd-wrapper[1598]: 0fd2df5633f0: Pull complete Sep 4 17:56:54.474012 systemd[1]: var-lib-docker-overlay2-b68f97435c17158f3bff611ee678afaf2eaec47c9266d31e931e3b3e24625ac9-merged.mount: Deactivated successfully. Sep 4 17:56:54.488350 etcd-wrapper[1598]: 8cc22b9456bb: Pull complete Sep 4 17:56:54.517194 systemd[1]: var-lib-docker-overlay2-5eb52f3ac6880aa93ca0d708f90ed136a90c075754be0adc853dd7a4d82c8f29-merged.mount: Deactivated successfully. Sep 4 17:56:54.528145 etcd-wrapper[1598]: 7ac70aecd290: Pull complete Sep 4 17:56:54.559124 sshd[1657]: pam_unix(sshd:session): session closed for user core Sep 4 17:56:54.571145 systemd[1]: sshd@1-172.24.4.27:22-172.24.4.1:35000.service: Deactivated successfully. Sep 4 17:56:54.574790 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 17:56:54.576787 etcd-wrapper[1598]: 4b376c64dfe4: Pull complete Sep 4 17:56:54.578412 systemd-logind[1352]: Session 4 logged out. Waiting for processes to exit. Sep 4 17:56:54.588740 systemd[1]: Started sshd@2-172.24.4.27:22-172.24.4.1:40704.service - OpenSSH per-connection server daemon (172.24.4.1:40704). Sep 4 17:56:54.591874 systemd-logind[1352]: Removed session 4. Sep 4 17:56:54.602945 etcd-wrapper[1598]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b Sep 4 17:56:54.608838 etcd-wrapper[1598]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0 Sep 4 17:56:54.993260 containerd[1369]: time="2024-09-04T17:56:54.993101860Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:56:54.994472 containerd[1369]: time="2024-09-04T17:56:54.993529558Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:56:54.994472 containerd[1369]: time="2024-09-04T17:56:54.993599682Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:56:54.994472 containerd[1369]: time="2024-09-04T17:56:54.993770348Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:56:55.023164 systemd[1]: Started docker-993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743.scope - libcontainer container 993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743. Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"info","ts":1725472615.099932,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"info","ts":1725472615.1000483,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"warn","ts":1725472615.1000822,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"warn","ts":1725472615.100091,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"warn","ts":1725472615.1000984,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"warn","ts":1725472615.1001036,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:55.100Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.24.4.27:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.24.4.27:2380","--advertise-client-urls=http://172.24.4.27:2379","--discovery=https://discovery.etcd.io/a40cceb39895139d7097a31bd2a74955","--enable-v2=true"]} Sep 4 17:56:55.100484 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:55.100Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.24.4.27:2380"]} Sep 4 17:56:55.101098 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:55.100Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 4 17:56:55.101098 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:55.100Z","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":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9","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.27:2380"],"listen-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9=http://172.24.4.27:2380","initial-cluster-state":"new","initial-cluster-token":"https://discovery.etcd.io/a40cceb39895139d7097a31bd2a74955","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/a40cceb39895139d7097a31bd2a74955","discovery-proxy":"","downgrade-check-interval":"5s"} Sep 4 17:56:55.104741 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:55.104Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.500677ms"} Sep 4 17:56:55.467306 systemd[1]: var-lib-docker-overlay2-21164da26b9aab2dd32de4e038f6106592d1674f9fd4b16f8eacf20cc05dafa7-merged.mount: Deactivated successfully. Sep 4 17:56:55.534884 sshd[1687]: Accepted publickey for core from 172.24.4.1 port 40704 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:56:55.538165 sshd[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:56:55.546217 systemd-logind[1352]: New session 5 of user core. Sep 4 17:56:55.552319 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 17:56:56.211341 sshd[1687]: pam_unix(sshd:session): session closed for user core Sep 4 17:56:56.218262 systemd-logind[1352]: Session 5 logged out. Waiting for processes to exit. Sep 4 17:56:56.219915 systemd[1]: sshd@2-172.24.4.27:22-172.24.4.1:40704.service: Deactivated successfully. Sep 4 17:56:56.224311 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 17:56:56.226390 systemd-logind[1352]: Removed session 5. Sep 4 17:56:56.251148 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:56.250Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"https://discovery.etcd.io","self":"3a2eda88e17ad6eb"} Sep 4 17:56:56.251148 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:56.250Z","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} Sep 4 17:56:58.836961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:58.836Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"28ea4de9ada0f759"} Sep 4 17:56:58.836961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:58.836Z","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} Sep 4 17:56:59.478550 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.477Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"49b9e7a199480ce7"} Sep 4 17:56:59.478550 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.477Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"https://discovery.etcd.io","found-peers":3} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.486Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"3a2eda88e17ad6eb","cluster-id":"e48585feea04729a"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=()"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became follower at term 0"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a2eda88e17ad6eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became follower at term 1"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113)"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899)"} Sep 4 17:56:59.487961 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899 5312531916707597543)"} Sep 4 17:56:59.492328 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:56:59.492Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Sep 4 17:56:59.494841 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.494Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Sep 4 17:56:59.496630 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.496Z","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"} Sep 4 17:56:59.497953 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.497Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.497953 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.497Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.504221 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.502Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.504221 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.502Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.505363 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.505Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.505997 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.505Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759","remote-peer-urls":["http://172.24.4.160:2380"]} Sep 4 17:56:59.505997 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.505Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.505997 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.505Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.505997 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.505Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.510540 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.506Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.510540 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.507Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.510540 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.508Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.514836 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.514Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.514836 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.514Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7","remote-peer-urls":["http://172.24.4.8:2380"]} Sep 4 17:56:59.515538 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.514Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.518317 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.514Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.518317 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.515Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"3a2eda88e17ad6eb","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Sep 4 17:56:59.520442 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.519Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Sep 4 17:56:59.520753 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.520Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899 5312531916707597543)"} Sep 4 17:56:59.521516 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.521Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"28ea4de9ada0f759","added-peer-peer-urls":["http://172.24.4.160:2380"]} Sep 4 17:56:59.521945 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899 5312531916707597543)"} Sep 4 17:56:59.522329 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.521Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"3a2eda88e17ad6eb","added-peer-peer-urls":["http://172.24.4.27:2380"]} Sep 4 17:56:59.522728 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899 5312531916707597543)"} Sep 4 17:56:59.523606 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.522Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"49b9e7a199480ce7","added-peer-peer-urls":["http://172.24.4.8:2380"]} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:56:59.527Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.24.4.27:2380"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.24.4.27:2380"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"3a2eda88e17ad6eb","initial-advertise-peer-urls":["http://172.24.4.27:2380"],"listen-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"28ea4de9ada0f759","stream-type":"stream Message"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"28ea4de9ada0f759","stream-type":"stream MsgApp v2"} Sep 4 17:56:59.528347 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.527Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.535635 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.534Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.535841 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.535Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:56:59.851393 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.849Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"49b9e7a199480ce7","stream-type":"stream MsgApp v2"} Sep 4 17:56:59.851393 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.849Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.851393 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.849Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.851393 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.850Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"49b9e7a199480ce7","stream-type":"stream Message"} Sep 4 17:56:59.851393 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.850Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.856166 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.855Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.857290 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.856Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:56:59.875217 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.874Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"3a2eda88e17ad6eb","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Sep 4 17:56:59.882802 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 1, index: 3, vote: 0] cast MsgPreVote for 28ea4de9ada0f759 [logterm: 1, index: 3] at term 1"} Sep 4 17:56:59.886260 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [term: 1] received a MsgVote message with higher term from 28ea4de9ada0f759 [term: 2]"} Sep 4 17:56:59.886260 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became follower at term 2"} Sep 4 17:56:59.886260 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 1, index: 3, vote: 0] cast MsgVote for 28ea4de9ada0f759 [logterm: 1, index: 3] at term 2"} Sep 4 17:56:59.889562 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a2eda88e17ad6eb elected leader 28ea4de9ada0f759 at term 2"} Sep 4 17:56:59.896536 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.895Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"3a2eda88e17ad6eb","local-member-attributes":"{Name:a6c8ca84a0e246c1ac3ed6b36bc8b9b9 ClientURLs:[http://172.24.4.27:2379]}","request-path":"/0/members/3a2eda88e17ad6eb/attributes","cluster-id":"e48585feea04729a","publish-timeout":"7s"} Sep 4 17:56:59.898424 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.896Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Sep 4 17:56:59.898424 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.896Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Sep 4 17:56:59.898424 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.897Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Sep 4 17:56:59.898638 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 4 17:56:59.901692 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.900Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Sep 4 17:56:59.907516 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.906Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","cluster-version":"3.5"} Sep 4 17:56:59.907516 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:56:59.906Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 4 17:56:59.908532 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... Sep 4 17:57:00.170813 etcdctl[1742]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } Sep 4 17:57:00.225326 flannel-wrapper[1751]: Error response from daemon: No such container: flannel Sep 4 17:57:00.249668 flannel-wrapper[1756]: Error response from daemon: No such container: flannel Sep 4 17:57:00.275351 flannel-wrapper[1768]: Unable to find image 'quay.io/coreos/flannel:v0.14.0' locally Sep 4 17:57:01.863367 flannel-wrapper[1768]: v0.14.0: Pulling from coreos/flannel Sep 4 17:57:02.265164 flannel-wrapper[1768]: 801bfaa63ef2: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: e4264a7179f6: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: bc75ea45ad2e: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: 78648579d12a: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: 3393447261e4: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: 071b96dd834b: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: 4de2f0468a91: Pulling fs layer Sep 4 17:57:02.265164 flannel-wrapper[1768]: 78648579d12a: Waiting Sep 4 17:57:02.265164 flannel-wrapper[1768]: 3393447261e4: Waiting Sep 4 17:57:02.266278 flannel-wrapper[1768]: 071b96dd834b: Waiting Sep 4 17:57:02.266278 flannel-wrapper[1768]: 4de2f0468a91: Waiting Sep 4 17:57:02.680681 flannel-wrapper[1768]: bc75ea45ad2e: Verifying Checksum Sep 4 17:57:02.681143 flannel-wrapper[1768]: bc75ea45ad2e: Download complete Sep 4 17:57:02.728797 flannel-wrapper[1768]: 801bfaa63ef2: Verifying Checksum Sep 4 17:57:02.728991 flannel-wrapper[1768]: 801bfaa63ef2: Download complete Sep 4 17:57:02.739550 flannel-wrapper[1768]: e4264a7179f6: Verifying Checksum Sep 4 17:57:02.739550 flannel-wrapper[1768]: e4264a7179f6: Download complete Sep 4 17:57:02.900698 flannel-wrapper[1768]: 801bfaa63ef2: Pull complete Sep 4 17:57:03.137270 flannel-wrapper[1768]: 3393447261e4: Verifying Checksum Sep 4 17:57:03.137270 flannel-wrapper[1768]: 3393447261e4: Download complete Sep 4 17:57:03.137270 flannel-wrapper[1768]: 071b96dd834b: Verifying Checksum Sep 4 17:57:03.137872 flannel-wrapper[1768]: 071b96dd834b: Download complete Sep 4 17:57:03.184271 flannel-wrapper[1768]: 78648579d12a: Verifying Checksum Sep 4 17:57:03.184528 flannel-wrapper[1768]: 78648579d12a: Download complete Sep 4 17:57:03.338381 systemd[1]: var-lib-docker-overlay2-bb90dae8c1cca9e9e883aaae2c43279fa102052bc1cda5319332016c68cecfe0-merged.mount: Deactivated successfully. Sep 4 17:57:03.582834 flannel-wrapper[1768]: 4de2f0468a91: Download complete Sep 4 17:57:03.948541 flannel-wrapper[1768]: e4264a7179f6: Pull complete Sep 4 17:57:04.085316 systemd[1]: var-lib-docker-overlay2-badbc31e52a8de7f372615970621ddaf4535654c386e9e009b18c359a5e28314-merged.mount: Deactivated successfully. Sep 4 17:57:04.176112 flannel-wrapper[1768]: bc75ea45ad2e: Pull complete Sep 4 17:57:04.744804 systemd[1]: var-lib-docker-overlay2-57e42425be0cbcef476639fc481a7ff2cb418cb0beaf8e2cc1558d265632d478-merged.mount: Deactivated successfully. Sep 4 17:57:05.730270 flannel-wrapper[1768]: 78648579d12a: Pull complete Sep 4 17:57:05.769218 systemd[1]: var-lib-docker-overlay2-dbb41e05107b19402c5cb0ab9c375d1fd8bb9883bff290631ab7a71bb2646bf0-merged.mount: Deactivated successfully. Sep 4 17:57:05.787925 flannel-wrapper[1768]: 3393447261e4: Pull complete Sep 4 17:57:05.827197 systemd[1]: var-lib-docker-overlay2-a1cc433b6617e4098de7c2b3325c9b85e4a92e0135211f25d9410c97d1eba638-merged.mount: Deactivated successfully. Sep 4 17:57:05.842900 flannel-wrapper[1768]: 071b96dd834b: Pull complete Sep 4 17:57:05.906589 flannel-wrapper[1768]: 4de2f0468a91: Pull complete Sep 4 17:57:05.925243 flannel-wrapper[1768]: Digest: sha256:4a330b2f2e74046e493b2edc30d61fdebbdddaaedcb32d62736f25be8d3c64d5 Sep 4 17:57:05.930619 flannel-wrapper[1768]: Status: Downloaded newer image for quay.io/coreos/flannel:v0.14.0 Sep 4 17:57:06.114765 containerd[1369]: time="2024-09-04T17:57:06.113341536Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:57:06.114765 containerd[1369]: time="2024-09-04T17:57:06.114627802Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:57:06.114765 containerd[1369]: time="2024-09-04T17:57:06.114683428Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:06.115703 containerd[1369]: time="2024-09-04T17:57:06.115534560Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:06.152464 systemd[1]: Started docker-7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b.scope - libcontainer container 7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b. Sep 4 17:57:06.221246 systemd[1]: Started sshd@3-172.24.4.27:22-172.24.4.1:44836.service - OpenSSH per-connection server daemon (172.24.4.1:44836). Sep 4 17:57:06.225225 flannel-wrapper[1768]: I0904 17:57:06.224832 1 main.go:520] Determining IP address of default interface Sep 4 17:57:06.225980 flannel-wrapper[1768]: I0904 17:57:06.225676 1 main.go:533] Using interface with name eth0 and address 172.24.4.27 Sep 4 17:57:06.225980 flannel-wrapper[1768]: I0904 17:57:06.225692 1 main.go:550] Defaulting external address to interface address (172.24.4.27) Sep 4 17:57:06.225980 flannel-wrapper[1768]: I0904 17:57:06.225776 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: None Sep 4 17:57:06.225980 flannel-wrapper[1768]: I0904 17:57:06.225781 1 main.go:257] Installing signal handlers Sep 4 17:57:06.229672 flannel-wrapper[1768]: I0904 17:57:06.229461 1 main.go:392] Found network config - Backend type: vxlan Sep 4 17:57:06.230018 flannel-wrapper[1768]: I0904 17:57:06.229875 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false Sep 4 17:57:06.262610 flannel-wrapper[1768]: I0904 17:57:06.261773 1 local_manager.go:234] Picking subnet in range 10.254.1.0 ... 10.254.255.0 Sep 4 17:57:06.264354 flannel-wrapper[1768]: I0904 17:57:06.264158 1 local_manager.go:220] Allocated lease (10.254.95.0/24) to current node (172.24.4.27) Sep 4 17:57:06.264826 systemd-networkd[1287]: flannel.1: Link UP Sep 4 17:57:06.265232 systemd-networkd[1287]: flannel.1: Gained carrier Sep 4 17:57:06.266106 flannel-wrapper[1768]: I0904 17:57:06.265933 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 Sep 4 17:57:06.285215 flannel-wrapper[1768]: I0904 17:57:06.284954 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN Sep 4 17:57:06.287244 flannel-wrapper[1768]: I0904 17:57:06.287049 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Sep 4 17:57:06.288647 flannel-wrapper[1768]: I0904 17:57:06.288493 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN Sep 4 17:57:06.290018 flannel-wrapper[1768]: I0904 17:57:06.289788 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j MASQUERADE --random-fully Sep 4 17:57:06.291237 flannel-wrapper[1768]: I0904 17:57:06.291126 1 main.go:307] Setting up masking rules Sep 4 17:57:06.292232 flannel-wrapper[1768]: I0904 17:57:06.292084 1 main.go:315] Changing default FORWARD chain policy to ACCEPT Sep 4 17:57:06.292491 flannel-wrapper[1768]: I0904 17:57:06.292321 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env Sep 4 17:57:06.292491 flannel-wrapper[1768]: I0904 17:57:06.292333 1 main.go:327] Running backend. Sep 4 17:57:06.292700 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). Sep 4 17:57:06.293379 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 17:57:06.297015 flannel-wrapper[1768]: I0904 17:57:06.293046 1 vxlan_network.go:59] watching for new subnet leases Sep 4 17:57:06.297015 flannel-wrapper[1768]: I0904 17:57:06.296293 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules Sep 4 17:57:06.297015 flannel-wrapper[1768]: I0904 17:57:06.296307 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN Sep 4 17:57:06.299542 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Sep 4 17:57:06.300556 flannel-wrapper[1768]: I0904 17:57:06.300240 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Sep 4 17:57:06.303663 flannel-wrapper[1768]: I0904 17:57:06.303457 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.95.0/24 -j RETURN Sep 4 17:57:06.304569 flannel-wrapper[1768]: I0904 17:57:06.304240 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules Sep 4 17:57:06.304569 flannel-wrapper[1768]: I0904 17:57:06.304256 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -j ACCEPT Sep 4 17:57:06.305423 flannel-wrapper[1768]: I0904 17:57:06.305318 1 main.go:439] Waiting for 22h59m59.956703135s to renew lease Sep 4 17:57:06.306787 flannel-wrapper[1768]: I0904 17:57:06.306690 1 iptables.go:172] Deleting iptables rule: -d 10.254.0.0/16 -j ACCEPT Sep 4 17:57:06.306948 flannel-wrapper[1768]: I0904 17:57:06.306789 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully Sep 4 17:57:06.310481 flannel-wrapper[1768]: I0904 17:57:06.310320 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -j ACCEPT Sep 4 17:57:06.310747 flannel-wrapper[1768]: I0904 17:57:06.310404 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN Sep 4 17:57:06.314985 sh[1901]: + for try in 1 2 3 4 5 6 Sep 4 17:57:06.314985 sh[1901]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i Sep 4 17:57:06.315942 flannel-wrapper[1768]: I0904 17:57:06.315770 1 iptables.go:160] Adding iptables rule: -d 10.254.0.0/16 -j ACCEPT Sep 4 17:57:06.316837 flannel-wrapper[1768]: I0904 17:57:06.315871 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully Sep 4 17:57:06.319191 flannel-wrapper[1768]: I0904 17:57:06.318784 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.95.0/24 -j RETURN Sep 4 17:57:06.321942 flannel-wrapper[1768]: I0904 17:57:06.321768 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully Sep 4 17:57:06.341183 sh[1922]: Error response from daemon: No such container: flannel-docker-opts Sep 4 17:57:06.354414 sh[1927]: Error response from daemon: No such container: flannel-docker-opts Sep 4 17:57:06.493889 containerd[1369]: time="2024-09-04T17:57:06.493132080Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:57:06.493889 containerd[1369]: time="2024-09-04T17:57:06.493234129Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:57:06.493889 containerd[1369]: time="2024-09-04T17:57:06.493254197Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:06.493889 containerd[1369]: time="2024-09-04T17:57:06.493353642Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:06.526408 systemd[1]: Started docker-b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d.scope - libcontainer container b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d. Sep 4 17:57:06.563785 systemd[1]: docker-b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d.scope: Deactivated successfully. Sep 4 17:57:06.584059 containerd[1369]: time="2024-09-04T17:57:06.583711982Z" level=info msg="shim disconnected" id=b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d namespace=moby Sep 4 17:57:06.584059 containerd[1369]: time="2024-09-04T17:57:06.583972207Z" level=warning msg="cleaning up after shim disconnected" id=b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d namespace=moby Sep 4 17:57:06.584059 containerd[1369]: time="2024-09-04T17:57:06.583983673Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 17:57:06.584584 dockerd[1442]: time="2024-09-04T17:57:06.584354799Z" level=info msg="ignoring event" container=b871251580368e57c56623a6f6ee39910c98cd5998a204bb86f1969b640bd18d module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 17:57:06.625947 dockerd[1442]: time="2024-09-04T17:57:06.625840590Z" level=info msg="Processing signal 'terminated'" Sep 4 17:57:06.626069 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 4 17:57:06.635907 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.634Z","caller":"osutil/interrupt_unix.go:64","msg":"received signal; shutting down","signal":"terminated"} Sep 4 17:57:06.635907 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.634Z","caller":"embed/etcd.go:367","msg":"closing etcd server","name":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27:2379"]} Sep 4 17:57:06.638170 systemd[1]: docker-7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b.scope: Deactivated successfully. Sep 4 17:57:06.640161 etcd-wrapper[1598]: WARNING: 2024/09/04 17:57:06 [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... Sep 4 17:57:06.640161 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.636Z","caller":"etcdserver/server.go:1429","msg":"skipped leadership transfer; local server is not leader","local-member-id":"3a2eda88e17ad6eb","current-leader-member-id":"28ea4de9ada0f759"} Sep 4 17:57:06.640161 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.636Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.635799 1 main.go:376] shutdownHandler sent cancel signal... Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.635864 1 watch.go:40] context canceled, close receiver chan Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.635884 1 vxlan_network.go:74] evts chan closed Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.636071 1 watch.go:174] context canceled, close receiver chan Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.636103 1 main.go:432] Stopped monitoring lease Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.636112 1 main.go:345] Waiting for all goroutines to exit Sep 4 17:57:06.640613 flannel-wrapper[1768]: I0904 17:57:06.636137 1 main.go:348] Exiting cleanly... Sep 4 17:57:06.640908 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.640Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.640908 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.640Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.641778 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.641Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.641778 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.641Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.643039 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.642Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.644746 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.644Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759","error":"context canceled"} Sep 4 17:57:06.644837 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.644Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"28ea4de9ada0f759","error":"failed to read 28ea4de9ada0f759 on stream MsgApp v2 (context canceled)"} Sep 4 17:57:06.645101 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.644Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.646436 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759","error":"context canceled"} Sep 4 17:57:06.646620 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.646620 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:06.646620 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.647249 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.647249 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.646Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.647742 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.647Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649273 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.647Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.647Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7","error":"context canceled"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"49b9e7a199480ce7","error":"failed to read 49b9e7a199480ce7 on stream MsgApp v2 (context canceled)"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7","error":"context canceled"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:06.649370 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.648Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"3a2eda88e17ad6eb","remote-peer-id-stream-handler":"3a2eda88e17ad6eb","remote-peer-id-from":"28ea4de9ada0f759","cluster-id":"e48585feea04729a"} Sep 4 17:57:06.651441 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.649Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"3a2eda88e17ad6eb","remote-peer-id-stream-handler":"3a2eda88e17ad6eb","remote-peer-id-from":"49b9e7a199480ce7","cluster-id":"e48585feea04729a"} Sep 4 17:57:06.651441 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.650Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"3a2eda88e17ad6eb","remote-peer-id-stream-handler":"3a2eda88e17ad6eb","remote-peer-id-from":"28ea4de9ada0f759","cluster-id":"e48585feea04729a"} Sep 4 17:57:06.655148 etcd-wrapper[1598]: {"level":"warn","ts":"2024-09-04T17:57:06.654Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"3a2eda88e17ad6eb","remote-peer-id-stream-handler":"3a2eda88e17ad6eb","remote-peer-id-from":"49b9e7a199480ce7","cluster-id":"e48585feea04729a"} Sep 4 17:57:06.655148 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.654Z","caller":"embed/etcd.go:562","msg":"stopping serving peer traffic","address":"172.24.4.27:2380"} Sep 4 17:57:06.665905 dockerd[1442]: time="2024-09-04T17:57:06.665806686Z" level=info msg="ignoring event" container=7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 17:57:06.666700 containerd[1369]: time="2024-09-04T17:57:06.666505480Z" level=info msg="shim disconnected" id=7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b namespace=moby Sep 4 17:57:06.666700 containerd[1369]: time="2024-09-04T17:57:06.666554177Z" level=warning msg="cleaning up after shim disconnected" id=7795723db2ccb7c597eebf4d013c278b5a9c5315d8faf2fc07fb2bd68d49e06b namespace=moby Sep 4 17:57:06.666700 containerd[1369]: time="2024-09-04T17:57:06.666566183Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 17:57:06.755523 systemd[1]: var-lib-docker-overlay2-b5d7f2c94d46918b300876d2f667f802ede3ce313391c087c65319614d365bbc-merged.mount: Deactivated successfully. Sep 4 17:57:06.792858 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.791Z","caller":"embed/etcd.go:567","msg":"stopped serving peer traffic","address":"172.24.4.27:2380"} Sep 4 17:57:06.792858 etcd-wrapper[1598]: {"level":"info","ts":"2024-09-04T17:57:06.792Z","caller":"embed/etcd.go:369","msg":"closed etcd server","name":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27:2379"]} Sep 4 17:57:06.796439 systemd[1]: docker-993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743.scope: Deactivated successfully. Sep 4 17:57:06.829317 dockerd[1442]: time="2024-09-04T17:57:06.828684434Z" level=info msg="ignoring event" container=993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 17:57:06.829879 containerd[1369]: time="2024-09-04T17:57:06.829182014Z" level=info msg="shim disconnected" id=993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743 namespace=moby Sep 4 17:57:06.829879 containerd[1369]: time="2024-09-04T17:57:06.829253331Z" level=warning msg="cleaning up after shim disconnected" id=993f2e98eb7e6b0a065fe0f3da6454211798d666cc076f260006a83ace914743 namespace=moby Sep 4 17:57:06.829879 containerd[1369]: time="2024-09-04T17:57:06.829278044Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 17:57:06.869770 systemd[1]: var-lib-docker-overlay2-47e9e9fc1c5d439bded4fdc58ea2b52ead0fb345d80ff6dd827afcbf7ab83d8a-merged.mount: Deactivated successfully. Sep 4 17:57:06.886486 dockerd[1442]: time="2024-09-04T17:57:06.886405556Z" level=info msg="stopping event stream following graceful shutdown" error="" module=libcontainerd namespace=moby Sep 4 17:57:06.886835 dockerd[1442]: time="2024-09-04T17:57:06.886759890Z" level=info msg="Daemon shutdown complete" Sep 4 17:57:06.903711 systemd[1]: docker.service: Deactivated successfully. Sep 4 17:57:06.904224 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 4 17:57:06.904350 systemd[1]: docker.service: Consumed 6.480s CPU time. Sep 4 17:57:06.907574 systemd[1]: docker.socket: Deactivated successfully. Sep 4 17:57:06.908701 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 4 17:57:06.908755 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 4 17:57:06.911756 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 17:57:06.913434 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 17:57:06.916481 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 17:57:06.926431 (dockerd)[2083]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS Sep 4 17:57:06.976421 dockerd[2083]: time="2024-09-04T17:57:06.976139522Z" level=info msg="Starting up" Sep 4 17:57:07.002559 systemd[1]: var-lib-docker-overlay2-check\x2doverlayfs\x2dsupport471533771-merged.mount: Deactivated successfully. Sep 4 17:57:07.015137 dockerd[2083]: time="2024-09-04T17:57:07.014821294Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 4 17:57:07.048472 dockerd[2083]: time="2024-09-04T17:57:07.048392167Z" level=info msg="Loading containers: start." Sep 4 17:57:07.316763 sshd[1861]: Accepted publickey for core from 172.24.4.1 port 44836 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:07.320761 sshd[1861]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:07.329527 systemd-logind[1352]: New session 6 of user core. Sep 4 17:57:07.334588 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 17:57:07.396002 dockerd[2083]: time="2024-09-04T17:57:07.395896273Z" level=info msg="Loading containers: done." Sep 4 17:57:07.420622 dockerd[2083]: time="2024-09-04T17:57:07.420492649Z" 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 Sep 4 17:57:07.421189 dockerd[2083]: time="2024-09-04T17:57:07.420767602Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Sep 4 17:57:07.421189 dockerd[2083]: time="2024-09-04T17:57:07.420907613Z" level=info msg="Daemon has completed initialization" Sep 4 17:57:07.428808 systemd-networkd[1287]: flannel.1: Gained IPv6LL Sep 4 17:57:07.478080 dockerd[2083]: time="2024-09-04T17:57:07.477163421Z" level=info msg="API listen on /run/docker.sock" Sep 4 17:57:07.477505 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 17:57:07.481092 docker[2076]: etcd-member Sep 4 17:57:07.484895 sh[1912]: Restarted Docker to apply Flannel options Sep 4 17:57:07.485160 sh[1901]: + break Sep 4 17:57:07.485160 sh[1901]: + '[' 1 '!=' fail ']' Sep 4 17:57:07.485938 docker[2049]: flannel Sep 4 17:57:07.489620 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Sep 4 17:57:07.490127 systemd[1]: Startup finished in 1.139s (kernel) + 11.947s (initrd) + 25.823s (userspace) = 38.910s. Sep 4 17:57:07.530114 docker[2225]: etcd-member Sep 4 17:57:07.532982 docker[2226]: flannel Sep 4 17:57:07.533899 systemd[1]: etcd-member.service: Deactivated successfully. Sep 4 17:57:07.534717 systemd[1]: flanneld.service: Deactivated successfully. Sep 4 17:57:07.810473 sshd[1861]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:07.822908 systemd[1]: sshd@3-172.24.4.27:22-172.24.4.1:44836.service: Deactivated successfully. Sep 4 17:57:07.827261 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 17:57:07.831463 systemd-logind[1352]: Session 6 logged out. Waiting for processes to exit. Sep 4 17:57:07.843648 systemd[1]: Started sshd@4-172.24.4.27:22-172.24.4.1:44852.service - OpenSSH per-connection server daemon (172.24.4.1:44852). Sep 4 17:57:07.846301 systemd-logind[1352]: Removed session 6. Sep 4 17:57:09.022005 sshd[2245]: Accepted publickey for core from 172.24.4.1 port 44852 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:09.025004 sshd[2245]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:09.036245 systemd-logind[1352]: New session 7 of user core. Sep 4 17:57:09.043353 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 17:57:09.781447 sshd[2245]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:09.790616 systemd[1]: sshd@4-172.24.4.27:22-172.24.4.1:44852.service: Deactivated successfully. Sep 4 17:57:09.793592 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 17:57:09.795004 systemd-logind[1352]: Session 7 logged out. Waiting for processes to exit. Sep 4 17:57:09.805350 systemd[1]: Started sshd@5-172.24.4.27:22-172.24.4.1:44854.service - OpenSSH per-connection server daemon (172.24.4.1:44854). Sep 4 17:57:09.808599 systemd-logind[1352]: Removed session 7. Sep 4 17:57:10.891798 sshd[2252]: Accepted publickey for core from 172.24.4.1 port 44854 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:10.895158 sshd[2252]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:10.904304 systemd-logind[1352]: New session 8 of user core. Sep 4 17:57:10.916359 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 17:57:11.517995 sshd[2252]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:11.531013 systemd[1]: sshd@5-172.24.4.27:22-172.24.4.1:44854.service: Deactivated successfully. Sep 4 17:57:11.534157 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 17:57:11.537326 systemd-logind[1352]: Session 8 logged out. Waiting for processes to exit. Sep 4 17:57:11.545643 systemd[1]: Started sshd@6-172.24.4.27:22-172.24.4.1:44856.service - OpenSSH per-connection server daemon (172.24.4.1:44856). Sep 4 17:57:11.548542 systemd-logind[1352]: Removed session 8. Sep 4 17:57:13.021340 sshd[2259]: Accepted publickey for core from 172.24.4.1 port 44856 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:13.056371 sshd[2259]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:13.067908 systemd-logind[1352]: New session 9 of user core. Sep 4 17:57:13.075557 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 17:57:13.439589 sudo[2262]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 4 17:57:13.440831 sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 17:57:13.462702 sudo[2262]: pam_unix(sudo:session): session closed for user root Sep 4 17:57:13.741465 sshd[2259]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:13.752956 systemd[1]: sshd@6-172.24.4.27:22-172.24.4.1:44856.service: Deactivated successfully. Sep 4 17:57:13.756528 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 17:57:13.760418 systemd-logind[1352]: Session 9 logged out. Waiting for processes to exit. Sep 4 17:57:13.767642 systemd[1]: Started sshd@7-172.24.4.27:22-172.24.4.1:44866.service - OpenSSH per-connection server daemon (172.24.4.1:44866). Sep 4 17:57:13.770722 systemd-logind[1352]: Removed session 9. Sep 4 17:57:14.901486 sshd[2267]: Accepted publickey for core from 172.24.4.1 port 44866 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:14.904652 sshd[2267]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:14.914475 systemd-logind[1352]: New session 10 of user core. Sep 4 17:57:14.926422 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 17:57:15.367857 sudo[2271]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 4 17:57:15.368927 sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 17:57:15.375274 sudo[2271]: pam_unix(sudo:session): session closed for user root Sep 4 17:57:15.385328 sudo[2270]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Sep 4 17:57:15.385938 sudo[2270]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 17:57:15.412863 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Sep 4 17:57:15.416101 auditctl[2274]: No rules Sep 4 17:57:15.416341 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 17:57:15.416680 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Sep 4 17:57:15.421535 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 17:57:15.464862 augenrules[2292]: No rules Sep 4 17:57:15.466146 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 17:57:15.467426 sudo[2270]: pam_unix(sudo:session): session closed for user root Sep 4 17:57:15.651648 sshd[2267]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:15.657185 systemd[1]: sshd@7-172.24.4.27:22-172.24.4.1:44866.service: Deactivated successfully. Sep 4 17:57:15.660623 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 17:57:15.663360 systemd-logind[1352]: Session 10 logged out. Waiting for processes to exit. Sep 4 17:57:15.665558 systemd-logind[1352]: Removed session 10. Sep 4 17:57:16.872570 systemd[1]: Started sshd@8-172.24.4.27:22-172.24.4.1:45836.service - OpenSSH per-connection server daemon (172.24.4.1:45836). Sep 4 17:57:17.649873 systemd[1]: etcd-member.service: Scheduled restart job, restart counter is at 1. Sep 4 17:57:17.652134 systemd[1]: flanneld.service: Scheduled restart job, restart counter is at 1. Sep 4 17:57:17.662377 systemd[1]: Stopping flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Sep 4 17:57:17.691127 docker[2302]: flannel-docker-opts Sep 4 17:57:17.747891 docker[2308]: flannel-docker-opts Sep 4 17:57:17.749347 systemd[1]: flannel-docker-opts.service: Deactivated successfully. Sep 4 17:57:17.749566 systemd[1]: Stopped flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Sep 4 17:57:17.750537 systemd[1]: Stopped flanneld.service - flannel - Network fabric for containers (System Application Container). Sep 4 17:57:17.755288 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 4 17:57:17.756800 (-wrapper)[2314]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 4 17:57:17.777562 etcd-wrapper[2318]: Error response from daemon: No such container: etcd-member Sep 4 17:57:17.791041 etcd-wrapper[2323]: Error response from daemon: No such container: etcd-member Sep 4 17:57:17.837749 systemd[1]: var-lib-docker-overlay2-cf40e22cd7beda5377d3f1163c010b0c6063f87bbe0901f49596dfe497881b75\x2dinit-merged.mount: Deactivated successfully. Sep 4 17:57:17.942561 containerd[1369]: time="2024-09-04T17:57:17.941813328Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:57:17.942561 containerd[1369]: time="2024-09-04T17:57:17.941889920Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:57:17.942561 containerd[1369]: time="2024-09-04T17:57:17.941941135Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:17.942561 containerd[1369]: time="2024-09-04T17:57:17.942129023Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:17.977201 systemd[1]: Started docker-bb1ecd323b00b8701de6afbc08b51c16324d636eb21a216f24dda35bce6c37d1.scope - libcontainer container bb1ecd323b00b8701de6afbc08b51c16324d636eb21a216f24dda35bce6c37d1. Sep 4 17:57:18.064722 etcd-wrapper[2338]: {"level":"info","ts":1725472638.064298,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"info","ts":1725472638.0644526,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"warn","ts":1725472638.0644865,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"warn","ts":1725472638.0644946,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"warn","ts":1725472638.0645018,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"warn","ts":1725472638.0645072,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.064Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.24.4.27:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.24.4.27:2380","--advertise-client-urls=http://172.24.4.27:2379","--discovery=https://discovery.etcd.io/a40cceb39895139d7097a31bd2a74955","--enable-v2=true"]} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.064Z","caller":"etcdmain/etcd.go:115","msg":"server has been already initialized","data-dir":"/var/lib/etcd","dir-type":"member"} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.064Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.24.4.27:2380"]} Sep 4 17:57:18.066994 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.064Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 4 17:57:18.067486 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.065Z","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":"a6c8ca84a0e246c1ac3ed6b36bc8b9b9","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.27:2380"],"listen-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27: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/a40cceb39895139d7097a31bd2a74955","discovery-proxy":"","downgrade-check-interval":"5s"} Sep 4 17:57:18.067486 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"209.899µs"} Sep 4 17:57:18.067486 etcd-wrapper[2338]: {"level":"warn","ts":"2024-09-04T17:57:18.065Z","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"} Sep 4 17:57:18.067486 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.066Z","caller":"etcdserver/server.go:526","msg":"No snapshot found. Recovering WAL from scratch!"} Sep 4 17:57:18.067486 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.066Z","caller":"etcdserver/raft.go:483","msg":"restarting local member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","commit-index":30} Sep 4 17:57:18.067730 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=()"} Sep 4 17:57:18.067730 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became follower at term 2"} Sep 4 17:57:18.067730 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a2eda88e17ad6eb [peers: [], term: 2, commit: 30, applied: 0, lastindex: 30, lastterm: 2]"} Sep 4 17:57:18.068886 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.068Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 4 17:57:18.068886 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.068Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","recovered-remote-peer-id":"28ea4de9ada0f759","recovered-remote-peer-urls":["http://172.24.4.160:2380"]} Sep 4 17:57:18.068886 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.068Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","recovered-remote-peer-id":"3a2eda88e17ad6eb","recovered-remote-peer-urls":["http://172.24.4.27:2380"]} Sep 4 17:57:18.068886 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.068Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","recovered-remote-peer-id":"49b9e7a199480ce7","recovered-remote-peer-urls":["http://172.24.4.8:2380"]} Sep 4 17:57:18.068886 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.068Z","caller":"membership/cluster.go:285","msg":"set cluster version from store","cluster-version":"3.5"} Sep 4 17:57:18.069884 etcd-wrapper[2338]: {"level":"warn","ts":"2024-09-04T17:57:18.069Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Sep 4 17:57:18.072168 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.071Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Sep 4 17:57:18.073139 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.072Z","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"} Sep 4 17:57:18.073958 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.073Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.073958 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.073Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.077684 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.074Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.077684 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.075Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.077684 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.075Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759","remote-peer-urls":["http://172.24.4.160:2380"]} Sep 4 17:57:18.077684 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.075Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.077684 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.075Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.078288 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.077Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.078288 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.077Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7","remote-peer-urls":["http://172.24.4.8:2380"]} Sep 4 17:57:18.078288 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.077Z","caller":"etcdserver/server.go:834","msg":"starting etcd server","local-member-id":"3a2eda88e17ad6eb","local-server-version":"3.5.0","cluster-id":"e48585feea04729a","cluster-version":"3.5"} Sep 4 17:57:18.080254 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.078Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.080411 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.079Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.080411 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.079Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.080411 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.079Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Sep 4 17:57:18.080970 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.080Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.081143 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.080Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.081143 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.080Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.081568 etcd-wrapper[2338]: {"level":"warn","ts":"2024-09-04T17:57:18.081Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} Sep 4 17:57:18.081783 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.081Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"3a2eda88e17ad6eb","initial-advertise-peer-urls":["http://172.24.4.27:2380"],"listen-peer-urls":["http://172.24.4.27:2380"],"advertise-client-urls":["http://172.24.4.27:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.081Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.24.4.27:2380"} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.24.4.27:2380"} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113)"} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"28ea4de9ada0f759","added-peer-peer-urls":["http://172.24.4.160:2380"]} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899)"} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"3a2eda88e17ad6eb","added-peer-peer-urls":["http://172.24.4.27:2380"]} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb switched to configuration voters=(2948254572103137113 4192528584561841899 5312531916707597543)"} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","added-peer-id":"49b9e7a199480ce7","added-peer-peer-urls":["http://172.24.4.8:2380"]} Sep 4 17:57:18.084109 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.082Z","caller":"membership/cluster.go:523","msg":"updated cluster version","cluster-id":"e48585feea04729a","local-member-id":"3a2eda88e17ad6eb","from":"3.5","to":"3.5"} Sep 4 17:57:18.084528 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.083Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.101855 sshd[2300]: Accepted publickey for core from 172.24.4.1 port 45836 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:18.103803 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.103Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"28ea4de9ada0f759","stream-type":"stream Message"} Sep 4 17:57:18.104681 sshd[2300]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:18.105561 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.105Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.106213 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.106Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.106213 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.105Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"28ea4de9ada0f759","stream-type":"stream MsgApp v2"} Sep 4 17:57:18.106457 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.106Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.111607 systemd-logind[1352]: New session 11 of user core. Sep 4 17:57:18.118346 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 17:57:18.186629 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.186Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.187567 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.187Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"28ea4de9ada0f759"} Sep 4 17:57:18.209055 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.208Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"49b9e7a199480ce7","stream-type":"stream Message"} Sep 4 17:57:18.209055 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.208Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.209055 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.208Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.209685 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.208Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"3a2eda88e17ad6eb","to":"49b9e7a199480ce7","stream-type":"stream MsgApp v2"} Sep 4 17:57:18.209685 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.208Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.233119 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.232Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"3a2eda88e17ad6eb","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Sep 4 17:57:18.287178 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.286Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.287694 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.286Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"3a2eda88e17ad6eb","remote-peer-id":"49b9e7a199480ce7"} Sep 4 17:57:18.470022 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb is starting a new election at term 2"} Sep 4 17:57:18.470022 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became pre-candidate at term 2"} Sep 4 17:57:18.471180 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb received MsgPreVoteResp from 3a2eda88e17ad6eb at term 2"} Sep 4 17:57:18.471180 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.470Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 2, index: 30] sent MsgPreVote request to 28ea4de9ada0f759 at term 2"} Sep 4 17:57:18.471180 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.470Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 2, index: 30] sent MsgPreVote request to 49b9e7a199480ce7 at term 2"} Sep 4 17:57:18.472920 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb received MsgPreVoteResp from 49b9e7a199480ce7 at term 2"} Sep 4 17:57:18.473502 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb has received 2 MsgPreVoteResp votes and 0 vote rejections"} Sep 4 17:57:18.473502 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became candidate at term 3"} Sep 4 17:57:18.473502 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb received MsgVoteResp from 3a2eda88e17ad6eb at term 3"} Sep 4 17:57:18.473502 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 2, index: 30] sent MsgVote request to 28ea4de9ada0f759 at term 3"} Sep 4 17:57:18.473502 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb [logterm: 2, index: 30] sent MsgVote request to 49b9e7a199480ce7 at term 3"} Sep 4 17:57:18.483377 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb received MsgVoteResp from 28ea4de9ada0f759 at term 3"} Sep 4 17:57:18.483377 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb has received 2 MsgVoteResp votes and 0 vote rejections"} Sep 4 17:57:18.483377 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a2eda88e17ad6eb became leader at term 3"} Sep 4 17:57:18.483377 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a2eda88e17ad6eb elected leader 3a2eda88e17ad6eb at term 3"} Sep 4 17:57:18.488819 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.487Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"3a2eda88e17ad6eb","local-member-attributes":"{Name:a6c8ca84a0e246c1ac3ed6b36bc8b9b9 ClientURLs:[http://172.24.4.27:2379]}","request-path":"/0/members/3a2eda88e17ad6eb/attributes","cluster-id":"e48585feea04729a","publish-timeout":"7s"} Sep 4 17:57:18.488819 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.488Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Sep 4 17:57:18.493158 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.491Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Sep 4 17:57:18.494548 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.493Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Sep 4 17:57:18.496585 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 4 17:57:18.500526 etcd-wrapper[2338]: {"level":"info","ts":"2024-09-04T17:57:18.495Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Sep 4 17:57:18.510239 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... Sep 4 17:57:18.578385 etcdctl[2401]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } Sep 4 17:57:18.606857 flannel-wrapper[2409]: Error response from daemon: No such container: flannel Sep 4 17:57:18.620686 flannel-wrapper[2414]: Error response from daemon: No such container: flannel Sep 4 17:57:18.775454 containerd[1369]: time="2024-09-04T17:57:18.774832497Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:57:18.775454 containerd[1369]: time="2024-09-04T17:57:18.775000298Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:57:18.775454 containerd[1369]: time="2024-09-04T17:57:18.775083682Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:18.775956 containerd[1369]: time="2024-09-04T17:57:18.775310983Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:18.803341 systemd[1]: Started docker-dd12a27b6b5ef2615fd294bd88f8a52352f7f52d459c478aa847f559a8849c60.scope - libcontainer container dd12a27b6b5ef2615fd294bd88f8a52352f7f52d459c478aa847f559a8849c60. Sep 4 17:57:18.822800 sshd[2300]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:18.836394 systemd[1]: sshd@8-172.24.4.27:22-172.24.4.1:45836.service: Deactivated successfully. Sep 4 17:57:18.840663 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 17:57:18.843401 systemd-logind[1352]: Session 11 logged out. Waiting for processes to exit. Sep 4 17:57:18.855166 systemd[1]: Started sshd@9-172.24.4.27:22-172.24.4.1:45852.service - OpenSSH per-connection server daemon (172.24.4.1:45852). Sep 4 17:57:18.857500 systemd-logind[1352]: Removed session 11. Sep 4 17:57:18.880093 flannel-wrapper[2427]: I0904 17:57:18.879757 1 main.go:520] Determining IP address of default interface Sep 4 17:57:18.881782 flannel-wrapper[2427]: I0904 17:57:18.881545 1 main.go:533] Using interface with name eth0 and address 172.24.4.27 Sep 4 17:57:18.882266 flannel-wrapper[2427]: I0904 17:57:18.882143 1 main.go:550] Defaulting external address to interface address (172.24.4.27) Sep 4 17:57:18.882807 flannel-wrapper[2427]: I0904 17:57:18.882624 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: 10.254.95.0/24 Sep 4 17:57:18.882807 flannel-wrapper[2427]: I0904 17:57:18.882669 1 main.go:257] Installing signal handlers Sep 4 17:57:18.886107 flannel-wrapper[2427]: I0904 17:57:18.885840 1 main.go:392] Found network config - Backend type: vxlan Sep 4 17:57:18.886914 flannel-wrapper[2427]: I0904 17:57:18.886123 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false Sep 4 17:57:18.896250 flannel-wrapper[2427]: I0904 17:57:18.895969 1 local_manager.go:147] Found lease (10.254.95.0/24) for current IP (172.24.4.27), reusing Sep 4 17:57:18.898452 flannel-wrapper[2427]: I0904 17:57:18.898227 1 main.go:307] Setting up masking rules Sep 4 17:57:18.913704 flannel-wrapper[2427]: I0904 17:57:18.913442 1 main.go:315] Changing default FORWARD chain policy to ACCEPT Sep 4 17:57:18.914692 flannel-wrapper[2427]: I0904 17:57:18.913787 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env Sep 4 17:57:18.914692 flannel-wrapper[2427]: I0904 17:57:18.913802 1 main.go:327] Running backend. Sep 4 17:57:18.914344 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). Sep 4 17:57:18.916835 flannel-wrapper[2427]: I0904 17:57:18.916640 1 vxlan_network.go:59] watching for new subnet leases Sep 4 17:57:18.919938 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... Sep 4 17:57:18.926813 flannel-wrapper[2427]: I0904 17:57:18.925952 1 main.go:439] Waiting for 22h59m59.970582756s to renew lease Sep 4 17:57:18.937956 sh[2512]: + for try in 1 2 3 4 5 6 Sep 4 17:57:18.937956 sh[2512]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i Sep 4 17:57:18.963471 sh[2521]: Error response from daemon: No such container: flannel-docker-opts Sep 4 17:57:18.984289 sh[2526]: Error response from daemon: No such container: flannel-docker-opts Sep 4 17:57:19.049650 systemd[1]: var-lib-docker-overlay2-dd35283a1197df862363debe468422b265a267a34d8eb69ce0d2a4cb23f55254-merged.mount: Deactivated successfully. Sep 4 17:57:19.241569 containerd[1369]: time="2024-09-04T17:57:19.240783958Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:57:19.241569 containerd[1369]: time="2024-09-04T17:57:19.240931431Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:57:19.241569 containerd[1369]: time="2024-09-04T17:57:19.240980232Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:19.241569 containerd[1369]: time="2024-09-04T17:57:19.241381205Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:57:19.288501 systemd[1]: Started docker-17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66.scope - libcontainer container 17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66. Sep 4 17:57:19.350681 systemd[1]: docker-17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66.scope: Deactivated successfully. Sep 4 17:57:19.369253 containerd[1369]: time="2024-09-04T17:57:19.369160364Z" level=info msg="shim disconnected" id=17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66 namespace=moby Sep 4 17:57:19.369415 dockerd[2083]: time="2024-09-04T17:57:19.369175040Z" level=info msg="ignoring event" container=17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 17:57:19.370677 containerd[1369]: time="2024-09-04T17:57:19.369891880Z" level=warning msg="cleaning up after shim disconnected" id=17048aeb32d852b9869fc357ff667e4c8b55e92fe49a19d9084f9b3c89668f66 namespace=moby Sep 4 17:57:19.370677 containerd[1369]: time="2024-09-04T17:57:19.369913180Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 17:57:19.386380 containerd[1369]: time="2024-09-04T17:57:19.386298955Z" level=warning msg="cleanup warnings time=\"2024-09-04T17:57:19Z\" 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 Sep 4 17:57:19.405963 sh[2512]: + break Sep 4 17:57:19.406648 sh[2512]: + '[' 1 '!=' fail ']' Sep 4 17:57:19.407903 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). Sep 4 17:57:19.833684 systemd[1]: var-lib-docker-overlay2-dd35283a1197df862363debe468422b265a267a34d8eb69ce0d2a4cb23f55254-merged.mount: Deactivated successfully. Sep 4 17:57:20.095555 sshd[2479]: Accepted publickey for core from 172.24.4.1 port 45852 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:20.098929 sshd[2479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:20.110606 systemd-logind[1352]: New session 12 of user core. Sep 4 17:57:20.126416 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 4 17:57:20.873709 sshd[2479]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:20.882583 systemd[1]: sshd@9-172.24.4.27:22-172.24.4.1:45852.service: Deactivated successfully. Sep 4 17:57:20.886929 systemd[1]: session-12.scope: Deactivated successfully. Sep 4 17:57:20.889222 systemd-logind[1352]: Session 12 logged out. Waiting for processes to exit. Sep 4 17:57:20.891605 systemd-logind[1352]: Removed session 12. Sep 4 17:57:23.273804 systemd[1]: Started sshd@10-172.24.4.27:22-172.24.4.1:45856.service - OpenSSH per-connection server daemon (172.24.4.1:45856). Sep 4 17:57:24.699336 sshd[2625]: Accepted publickey for core from 172.24.4.1 port 45856 ssh2: RSA SHA256:JnA7Fh8lVkr6ENifNOXj431OPLJBOL+/PI8dMas4Eok Sep 4 17:57:24.702356 sshd[2625]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 17:57:24.713222 systemd-logind[1352]: New session 13 of user core. Sep 4 17:57:24.724370 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 4 17:57:30.586188 update_engine[1353]: I0904 17:57:30.585450 1353 update_attempter.cc:509] Updating boot flags... Sep 4 17:57:30.661878 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2649) Sep 4 17:57:30.729063 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2653) Sep 4 17:57:30.780262 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2653) Sep 4 17:57:34.762676 sshd[2625]: pam_unix(sshd:session): session closed for user core Sep 4 17:57:34.769224 systemd[1]: sshd@10-172.24.4.27:22-172.24.4.1:45856.service: Deactivated successfully. Sep 4 17:57:34.773616 systemd[1]: session-13.scope: Deactivated successfully. Sep 4 17:57:34.776584 systemd-logind[1352]: Session 13 logged out. Waiting for processes to exit. Sep 4 17:57:34.778968 systemd-logind[1352]: Removed session 13.