Aug 5 22:29:14.307847 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT_DYNAMIC Mon Aug 5 20:36:22 -00 2024 Aug 5 22:29:14.307899 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=4763ee6059e6f81f5b007c7bdf42f5dcad676aac40503ddb8a29787eba4ab695 Aug 5 22:29:14.307926 kernel: BIOS-provided physical RAM map: Aug 5 22:29:14.307938 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Aug 5 22:29:14.307947 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Aug 5 22:29:14.307957 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Aug 5 22:29:14.307977 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdcfff] usable Aug 5 22:29:14.307987 kernel: BIOS-e820: [mem 0x000000009cfdd000-0x000000009cffffff] reserved Aug 5 22:29:14.307997 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Aug 5 22:29:14.308012 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Aug 5 22:29:14.308023 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Aug 5 22:29:14.308033 kernel: NX (Execute Disable) protection: active Aug 5 22:29:14.308042 kernel: APIC: Static calls initialized Aug 5 22:29:14.308052 kernel: SMBIOS 2.8 present. Aug 5 22:29:14.308065 kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Aug 5 22:29:14.308079 kernel: Hypervisor detected: KVM Aug 5 22:29:14.308090 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Aug 5 22:29:14.308106 kernel: kvm-clock: using sched offset of 3746152313 cycles Aug 5 22:29:14.308118 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Aug 5 22:29:14.308129 kernel: tsc: Detected 2794.748 MHz processor Aug 5 22:29:14.308144 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 5 22:29:14.308156 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 5 22:29:14.308167 kernel: last_pfn = 0x9cfdd max_arch_pfn = 0x400000000 Aug 5 22:29:14.308178 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Aug 5 22:29:14.308193 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 5 22:29:14.308208 kernel: Using GB pages for direct mapping Aug 5 22:29:14.308228 kernel: ACPI: Early table checksum verification disabled Aug 5 22:29:14.308239 kernel: ACPI: RSDP 0x00000000000F59C0 000014 (v00 BOCHS ) Aug 5 22:29:14.308251 kernel: ACPI: RSDT 0x000000009CFE1BDD 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308262 kernel: ACPI: FACP 0x000000009CFE1A79 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308273 kernel: ACPI: DSDT 0x000000009CFE0040 001A39 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308284 kernel: ACPI: FACS 0x000000009CFE0000 000040 Aug 5 22:29:14.308296 kernel: ACPI: APIC 0x000000009CFE1AED 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308311 kernel: ACPI: HPET 0x000000009CFE1B7D 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308322 kernel: ACPI: WAET 0x000000009CFE1BB5 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:29:14.308334 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe1a79-0x9cfe1aec] Aug 5 22:29:14.308345 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe1a78] Aug 5 22:29:14.308355 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Aug 5 22:29:14.308375 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe1aed-0x9cfe1b7c] Aug 5 22:29:14.308391 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe1b7d-0x9cfe1bb4] Aug 5 22:29:14.308412 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe1bb5-0x9cfe1bdc] Aug 5 22:29:14.308423 kernel: No NUMA configuration found Aug 5 22:29:14.308435 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdcfff] Aug 5 22:29:14.308447 kernel: NODE_DATA(0) allocated [mem 0x9cfd7000-0x9cfdcfff] Aug 5 22:29:14.308458 kernel: Zone ranges: Aug 5 22:29:14.308470 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 5 22:29:14.308482 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdcfff] Aug 5 22:29:14.308497 kernel: Normal empty Aug 5 22:29:14.308517 kernel: Movable zone start for each node Aug 5 22:29:14.308530 kernel: Early memory node ranges Aug 5 22:29:14.308541 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Aug 5 22:29:14.308559 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdcfff] Aug 5 22:29:14.308571 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdcfff] Aug 5 22:29:14.308583 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 5 22:29:14.308598 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Aug 5 22:29:14.308613 kernel: On node 0, zone DMA32: 12323 pages in unavailable ranges Aug 5 22:29:14.308628 kernel: ACPI: PM-Timer IO Port: 0x608 Aug 5 22:29:14.308644 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Aug 5 22:29:14.308654 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Aug 5 22:29:14.308664 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 5 22:29:14.308674 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Aug 5 22:29:14.308686 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 5 22:29:14.308723 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Aug 5 22:29:14.308745 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Aug 5 22:29:14.308764 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 5 22:29:14.308801 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Aug 5 22:29:14.308821 kernel: TSC deadline timer available Aug 5 22:29:14.308834 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Aug 5 22:29:14.308847 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Aug 5 22:29:14.308859 kernel: kvm-guest: KVM setup pv remote TLB flush Aug 5 22:29:14.308872 kernel: kvm-guest: setup PV sched yield Aug 5 22:29:14.308883 kernel: [mem 0x9d000000-0xfeffbfff] available for PCI devices Aug 5 22:29:14.308894 kernel: Booting paravirtualized kernel on KVM Aug 5 22:29:14.308904 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 5 22:29:14.308922 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Aug 5 22:29:14.308935 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u524288 Aug 5 22:29:14.308945 kernel: pcpu-alloc: s196904 r8192 d32472 u524288 alloc=1*2097152 Aug 5 22:29:14.308954 kernel: pcpu-alloc: [0] 0 1 2 3 Aug 5 22:29:14.308964 kernel: kvm-guest: PV spinlocks enabled Aug 5 22:29:14.308974 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 5 22:29:14.308986 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,115200 flatcar.first_boot=detected verity.usrhash=4763ee6059e6f81f5b007c7bdf42f5dcad676aac40503ddb8a29787eba4ab695 Aug 5 22:29:14.308997 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 22:29:14.309009 kernel: random: crng init done Aug 5 22:29:14.309019 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 5 22:29:14.309029 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 22:29:14.309038 kernel: Fallback order for Node 0: 0 Aug 5 22:29:14.309050 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632733 Aug 5 22:29:14.309060 kernel: Policy zone: DMA32 Aug 5 22:29:14.309069 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 22:29:14.309079 kernel: Memory: 2428452K/2571756K available (12288K kernel code, 2302K rwdata, 22640K rodata, 49372K init, 1972K bss, 143044K reserved, 0K cma-reserved) Aug 5 22:29:14.309088 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 5 22:29:14.309101 kernel: ftrace: allocating 37659 entries in 148 pages Aug 5 22:29:14.309110 kernel: ftrace: allocated 148 pages with 3 groups Aug 5 22:29:14.309126 kernel: Dynamic Preempt: voluntary Aug 5 22:29:14.309141 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 22:29:14.309155 kernel: rcu: RCU event tracing is enabled. Aug 5 22:29:14.309166 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 5 22:29:14.309176 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 22:29:14.309186 kernel: Rude variant of Tasks RCU enabled. Aug 5 22:29:14.309196 kernel: Tracing variant of Tasks RCU enabled. Aug 5 22:29:14.309212 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 22:29:14.309222 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 5 22:29:14.309233 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Aug 5 22:29:14.309243 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 22:29:14.309253 kernel: Console: colour VGA+ 80x25 Aug 5 22:29:14.309272 kernel: printk: console [ttyS0] enabled Aug 5 22:29:14.309283 kernel: ACPI: Core revision 20230628 Aug 5 22:29:14.309293 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Aug 5 22:29:14.309303 kernel: APIC: Switch to symmetric I/O mode setup Aug 5 22:29:14.309313 kernel: x2apic enabled Aug 5 22:29:14.309328 kernel: APIC: Switched APIC routing to: physical x2apic Aug 5 22:29:14.309338 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Aug 5 22:29:14.309351 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Aug 5 22:29:14.309364 kernel: kvm-guest: setup PV IPIs Aug 5 22:29:14.309375 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Aug 5 22:29:14.309386 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Aug 5 22:29:14.309406 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Aug 5 22:29:14.309420 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Aug 5 22:29:14.309445 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Aug 5 22:29:14.309456 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Aug 5 22:29:14.309467 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 5 22:29:14.309481 kernel: Spectre V2 : Mitigation: Retpolines Aug 5 22:29:14.309493 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Aug 5 22:29:14.309504 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Aug 5 22:29:14.309515 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Aug 5 22:29:14.309526 kernel: RETBleed: Mitigation: untrained return thunk Aug 5 22:29:14.309537 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Aug 5 22:29:14.309552 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Aug 5 22:29:14.309563 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Aug 5 22:29:14.309578 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Aug 5 22:29:14.309589 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Aug 5 22:29:14.309601 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 5 22:29:14.309612 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 5 22:29:14.309622 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 5 22:29:14.309636 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 5 22:29:14.309647 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Aug 5 22:29:14.309658 kernel: Freeing SMP alternatives memory: 32K Aug 5 22:29:14.309670 kernel: pid_max: default: 32768 minimum: 301 Aug 5 22:29:14.309681 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 22:29:14.309692 kernel: SELinux: Initializing. Aug 5 22:29:14.309723 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:29:14.309747 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 22:29:14.309758 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Aug 5 22:29:14.309774 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:29:14.309785 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:29:14.309796 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:29:14.309807 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Aug 5 22:29:14.309818 kernel: ... version: 0 Aug 5 22:29:14.309829 kernel: ... bit width: 48 Aug 5 22:29:14.309840 kernel: ... generic registers: 6 Aug 5 22:29:14.309851 kernel: ... value mask: 0000ffffffffffff Aug 5 22:29:14.309862 kernel: ... max period: 00007fffffffffff Aug 5 22:29:14.309877 kernel: ... fixed-purpose events: 0 Aug 5 22:29:14.309888 kernel: ... event mask: 000000000000003f Aug 5 22:29:14.309898 kernel: signal: max sigframe size: 1776 Aug 5 22:29:14.309909 kernel: rcu: Hierarchical SRCU implementation. Aug 5 22:29:14.309920 kernel: rcu: Max phase no-delay instances is 400. Aug 5 22:29:14.309931 kernel: smp: Bringing up secondary CPUs ... Aug 5 22:29:14.309942 kernel: smpboot: x86: Booting SMP configuration: Aug 5 22:29:14.309953 kernel: .... node #0, CPUs: #1 #2 #3 Aug 5 22:29:14.309968 kernel: smp: Brought up 1 node, 4 CPUs Aug 5 22:29:14.309983 kernel: smpboot: Max logical packages: 1 Aug 5 22:29:14.309994 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Aug 5 22:29:14.310005 kernel: devtmpfs: initialized Aug 5 22:29:14.310016 kernel: x86/mm: Memory block size: 128MB Aug 5 22:29:14.310027 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 22:29:14.310039 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 5 22:29:14.310050 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 22:29:14.310061 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 22:29:14.310072 kernel: audit: initializing netlink subsys (disabled) Aug 5 22:29:14.310083 kernel: audit: type=2000 audit(1722896952.809:1): state=initialized audit_enabled=0 res=1 Aug 5 22:29:14.310097 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 22:29:14.310109 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 5 22:29:14.310120 kernel: cpuidle: using governor menu Aug 5 22:29:14.310131 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 22:29:14.310142 kernel: dca service started, version 1.12.1 Aug 5 22:29:14.310153 kernel: PCI: Using configuration type 1 for base access Aug 5 22:29:14.310163 kernel: PCI: Using configuration type 1 for extended access Aug 5 22:29:14.310174 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 5 22:29:14.310189 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 5 22:29:14.310200 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 5 22:29:14.310210 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 22:29:14.310221 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 22:29:14.310232 kernel: ACPI: Added _OSI(Module Device) Aug 5 22:29:14.310242 kernel: ACPI: Added _OSI(Processor Device) Aug 5 22:29:14.310253 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 22:29:14.310264 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 22:29:14.310275 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 22:29:14.310286 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 5 22:29:14.310301 kernel: ACPI: Interpreter enabled Aug 5 22:29:14.310312 kernel: ACPI: PM: (supports S0 S3 S5) Aug 5 22:29:14.310323 kernel: ACPI: Using IOAPIC for interrupt routing Aug 5 22:29:14.310334 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 5 22:29:14.310345 kernel: PCI: Using E820 reservations for host bridge windows Aug 5 22:29:14.310355 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Aug 5 22:29:14.310366 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 5 22:29:14.310649 kernel: acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 5 22:29:14.310674 kernel: acpiphp: Slot [3] registered Aug 5 22:29:14.310686 kernel: acpiphp: Slot [4] registered Aug 5 22:29:14.310713 kernel: acpiphp: Slot [5] registered Aug 5 22:29:14.310724 kernel: acpiphp: Slot [6] registered Aug 5 22:29:14.310748 kernel: acpiphp: Slot [7] registered Aug 5 22:29:14.310759 kernel: acpiphp: Slot [8] registered Aug 5 22:29:14.310770 kernel: acpiphp: Slot [9] registered Aug 5 22:29:14.310780 kernel: acpiphp: Slot [10] registered Aug 5 22:29:14.310791 kernel: acpiphp: Slot [11] registered Aug 5 22:29:14.310807 kernel: acpiphp: Slot [12] registered Aug 5 22:29:14.310818 kernel: acpiphp: Slot [13] registered Aug 5 22:29:14.310829 kernel: acpiphp: Slot [14] registered Aug 5 22:29:14.310840 kernel: acpiphp: Slot [15] registered Aug 5 22:29:14.310851 kernel: acpiphp: Slot [16] registered Aug 5 22:29:14.310862 kernel: acpiphp: Slot [17] registered Aug 5 22:29:14.310873 kernel: acpiphp: Slot [18] registered Aug 5 22:29:14.310884 kernel: acpiphp: Slot [19] registered Aug 5 22:29:14.310895 kernel: acpiphp: Slot [20] registered Aug 5 22:29:14.310910 kernel: acpiphp: Slot [21] registered Aug 5 22:29:14.310921 kernel: acpiphp: Slot [22] registered Aug 5 22:29:14.310932 kernel: acpiphp: Slot [23] registered Aug 5 22:29:14.310942 kernel: acpiphp: Slot [24] registered Aug 5 22:29:14.310953 kernel: acpiphp: Slot [25] registered Aug 5 22:29:14.310963 kernel: acpiphp: Slot [26] registered Aug 5 22:29:14.310974 kernel: acpiphp: Slot [27] registered Aug 5 22:29:14.310984 kernel: acpiphp: Slot [28] registered Aug 5 22:29:14.310995 kernel: acpiphp: Slot [29] registered Aug 5 22:29:14.311006 kernel: acpiphp: Slot [30] registered Aug 5 22:29:14.311020 kernel: acpiphp: Slot [31] registered Aug 5 22:29:14.311031 kernel: PCI host bridge to bus 0000:00 Aug 5 22:29:14.311228 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 5 22:29:14.311385 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 5 22:29:14.311536 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Aug 5 22:29:14.311761 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xfebfffff window] Aug 5 22:29:14.311903 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Aug 5 22:29:14.312054 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 5 22:29:14.312253 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Aug 5 22:29:14.312447 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Aug 5 22:29:14.312632 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Aug 5 22:29:14.312879 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc0c0-0xc0cf] Aug 5 22:29:14.313047 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Aug 5 22:29:14.313214 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Aug 5 22:29:14.313396 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Aug 5 22:29:14.313563 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Aug 5 22:29:14.313863 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Aug 5 22:29:14.314039 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Aug 5 22:29:14.314210 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Aug 5 22:29:14.314401 kernel: pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 Aug 5 22:29:14.314576 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Aug 5 22:29:14.314774 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Aug 5 22:29:14.314943 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Aug 5 22:29:14.315114 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 5 22:29:14.315328 kernel: pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 Aug 5 22:29:14.315494 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc09f] Aug 5 22:29:14.315795 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Aug 5 22:29:14.316004 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Aug 5 22:29:14.316215 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Aug 5 22:29:14.316387 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Aug 5 22:29:14.316551 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Aug 5 22:29:14.316801 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Aug 5 22:29:14.317079 kernel: pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 Aug 5 22:29:14.317241 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0a0-0xc0bf] Aug 5 22:29:14.317414 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Aug 5 22:29:14.317594 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Aug 5 22:29:14.317783 kernel: pci 0000:00:05.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Aug 5 22:29:14.317799 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Aug 5 22:29:14.317809 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Aug 5 22:29:14.317817 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Aug 5 22:29:14.317828 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Aug 5 22:29:14.317837 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Aug 5 22:29:14.317851 kernel: iommu: Default domain type: Translated Aug 5 22:29:14.317859 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 5 22:29:14.317868 kernel: PCI: Using ACPI for IRQ routing Aug 5 22:29:14.317876 kernel: PCI: pci_cache_line_size set to 64 bytes Aug 5 22:29:14.317885 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Aug 5 22:29:14.317893 kernel: e820: reserve RAM buffer [mem 0x9cfdd000-0x9fffffff] Aug 5 22:29:14.318028 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 5 22:29:14.318177 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 5 22:29:14.318359 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 5 22:29:14.318378 kernel: vgaarb: loaded Aug 5 22:29:14.318390 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Aug 5 22:29:14.318403 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Aug 5 22:29:14.318415 kernel: clocksource: Switched to clocksource kvm-clock Aug 5 22:29:14.318428 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 22:29:14.318441 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 22:29:14.318454 kernel: pnp: PnP ACPI init Aug 5 22:29:14.318685 kernel: pnp 00:02: [dma 2] Aug 5 22:29:14.318798 kernel: pnp: PnP ACPI: found 6 devices Aug 5 22:29:14.318810 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 5 22:29:14.318820 kernel: NET: Registered PF_INET protocol family Aug 5 22:29:14.318831 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 5 22:29:14.318842 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 5 22:29:14.318853 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 22:29:14.318864 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 22:29:14.318877 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 5 22:29:14.318895 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 5 22:29:14.318908 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:29:14.318921 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 22:29:14.318933 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 22:29:14.318946 kernel: NET: Registered PF_XDP protocol family Aug 5 22:29:14.319128 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 5 22:29:14.319293 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 5 22:29:14.319476 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Aug 5 22:29:14.319663 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xfebfffff window] Aug 5 22:29:14.319883 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Aug 5 22:29:14.320066 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Aug 5 22:29:14.320254 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Aug 5 22:29:14.320281 kernel: PCI: CLS 0 bytes, default 64 Aug 5 22:29:14.320306 kernel: Initialise system trusted keyrings Aug 5 22:29:14.320315 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 5 22:29:14.320324 kernel: Key type asymmetric registered Aug 5 22:29:14.320333 kernel: Asymmetric key parser 'x509' registered Aug 5 22:29:14.320347 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 5 22:29:14.320355 kernel: io scheduler mq-deadline registered Aug 5 22:29:14.320364 kernel: io scheduler kyber registered Aug 5 22:29:14.320372 kernel: io scheduler bfq registered Aug 5 22:29:14.320381 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 5 22:29:14.320390 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Aug 5 22:29:14.320399 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10 Aug 5 22:29:14.320408 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Aug 5 22:29:14.320417 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 22:29:14.320434 kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 5 22:29:14.320458 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Aug 5 22:29:14.320469 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Aug 5 22:29:14.320478 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Aug 5 22:29:14.320691 kernel: rtc_cmos 00:05: RTC can wake from S4 Aug 5 22:29:14.320976 kernel: rtc_cmos 00:05: registered as rtc0 Aug 5 22:29:14.321133 kernel: rtc_cmos 00:05: setting system clock to 2024-08-05T22:29:13 UTC (1722896953) Aug 5 22:29:14.321287 kernel: rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Aug 5 22:29:14.321314 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Aug 5 22:29:14.321326 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Aug 5 22:29:14.321338 kernel: NET: Registered PF_INET6 protocol family Aug 5 22:29:14.321350 kernel: Segment Routing with IPv6 Aug 5 22:29:14.321361 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 22:29:14.321374 kernel: NET: Registered PF_PACKET protocol family Aug 5 22:29:14.321387 kernel: Key type dns_resolver registered Aug 5 22:29:14.321399 kernel: IPI shorthand broadcast: enabled Aug 5 22:29:14.321412 kernel: sched_clock: Marking stable (1660008891, 152670824)->(1904957034, -92277319) Aug 5 22:29:14.321428 kernel: registered taskstats version 1 Aug 5 22:29:14.321441 kernel: Loading compiled-in X.509 certificates Aug 5 22:29:14.321454 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: d8f193b4a33a492a73da7ce4522bbc835ec39532' Aug 5 22:29:14.321465 kernel: Key type .fscrypt registered Aug 5 22:29:14.321477 kernel: Key type fscrypt-provisioning registered Aug 5 22:29:14.321488 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 22:29:14.321500 kernel: ima: Allocated hash algorithm: sha1 Aug 5 22:29:14.321511 kernel: ima: No architecture policies found Aug 5 22:29:14.321523 kernel: clk: Disabling unused clocks Aug 5 22:29:14.321538 kernel: Freeing unused kernel image (initmem) memory: 49372K Aug 5 22:29:14.321550 kernel: Write protecting the kernel read-only data: 36864k Aug 5 22:29:14.321562 kernel: Freeing unused kernel image (rodata/data gap) memory: 1936K Aug 5 22:29:14.321573 kernel: Run /init as init process Aug 5 22:29:14.321585 kernel: with arguments: Aug 5 22:29:14.321597 kernel: /init Aug 5 22:29:14.321608 kernel: with environment: Aug 5 22:29:14.321620 kernel: HOME=/ Aug 5 22:29:14.321652 kernel: TERM=linux Aug 5 22:29:14.321670 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 22:29:14.321685 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:29:14.321719 systemd[1]: Detected virtualization kvm. Aug 5 22:29:14.321756 systemd[1]: Detected architecture x86-64. Aug 5 22:29:14.321768 systemd[1]: Running in initrd. Aug 5 22:29:14.321781 systemd[1]: No hostname configured, using default hostname. Aug 5 22:29:14.321793 systemd[1]: Hostname set to . Aug 5 22:29:14.321812 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:29:14.321835 systemd[1]: Queued start job for default target initrd.target. Aug 5 22:29:14.321850 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:29:14.321865 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:29:14.321882 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 22:29:14.321898 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:29:14.321911 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 22:29:14.321924 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 22:29:14.321944 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 22:29:14.321957 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 22:29:14.321975 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:29:14.322000 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:29:14.322027 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:29:14.322046 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:29:14.322059 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:29:14.322076 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:29:14.322095 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:29:14.322115 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:29:14.322128 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 22:29:14.322141 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 22:29:14.322153 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:29:14.322165 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:29:14.322178 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:29:14.322195 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:29:14.322212 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 22:29:14.322225 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:29:14.322238 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 22:29:14.322250 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 22:29:14.322263 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:29:14.322279 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:29:14.322291 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:29:14.322303 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 22:29:14.322321 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:29:14.322337 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 22:29:14.322352 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:29:14.322402 systemd-journald[193]: Collecting audit messages is disabled. Aug 5 22:29:14.322435 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:29:14.322452 systemd-journald[193]: Journal started Aug 5 22:29:14.322481 systemd-journald[193]: Runtime Journal (/run/log/journal/7cc6d108f3c840f283973aaf9aa0102c) is 6.0M, max 48.4M, 42.3M free. Aug 5 22:29:14.317613 systemd-modules-load[194]: Inserted module 'overlay' Aug 5 22:29:14.356875 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:29:14.358562 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:29:14.366807 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 22:29:14.371871 kernel: Bridge firewalling registered Aug 5 22:29:14.371831 systemd-modules-load[194]: Inserted module 'br_netfilter' Aug 5 22:29:14.380121 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:29:14.384666 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:29:14.388910 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:29:14.389297 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:29:14.392611 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:29:14.403242 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:29:14.406953 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:29:14.411468 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:29:14.431045 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 22:29:14.432737 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:29:14.438254 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:29:14.446124 dracut-cmdline[227]: dracut-dracut-053 Aug 5 22:29:14.451092 dracut-cmdline[227]: 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,115200 flatcar.first_boot=detected verity.usrhash=4763ee6059e6f81f5b007c7bdf42f5dcad676aac40503ddb8a29787eba4ab695 Aug 5 22:29:14.500872 systemd-resolved[232]: Positive Trust Anchors: Aug 5 22:29:14.500906 systemd-resolved[232]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:29:14.500947 systemd-resolved[232]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 22:29:14.514151 systemd-resolved[232]: Defaulting to hostname 'linux'. Aug 5 22:29:14.516512 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:29:14.519655 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:29:14.571767 kernel: SCSI subsystem initialized Aug 5 22:29:14.585760 kernel: Loading iSCSI transport class v2.0-870. Aug 5 22:29:14.603756 kernel: iscsi: registered transport (tcp) Aug 5 22:29:14.635806 kernel: iscsi: registered transport (qla4xxx) Aug 5 22:29:14.635879 kernel: QLogic iSCSI HBA Driver Aug 5 22:29:14.702613 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 22:29:14.714900 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 22:29:14.750569 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 22:29:14.750658 kernel: device-mapper: uevent: version 1.0.3 Aug 5 22:29:14.750672 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 22:29:14.804784 kernel: raid6: avx2x4 gen() 22712 MB/s Aug 5 22:29:14.821767 kernel: raid6: avx2x2 gen() 25807 MB/s Aug 5 22:29:14.839108 kernel: raid6: avx2x1 gen() 18621 MB/s Aug 5 22:29:14.839205 kernel: raid6: using algorithm avx2x2 gen() 25807 MB/s Aug 5 22:29:14.869350 kernel: raid6: .... xor() 15552 MB/s, rmw enabled Aug 5 22:29:14.869492 kernel: raid6: using avx2x2 recovery algorithm Aug 5 22:29:14.900758 kernel: xor: automatically using best checksumming function avx Aug 5 22:29:15.100773 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 22:29:15.118340 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:29:15.127097 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:29:15.148903 systemd-udevd[413]: Using default interface naming scheme 'v255'. Aug 5 22:29:15.155683 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:29:15.174160 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 22:29:15.194274 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Aug 5 22:29:15.240551 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:29:15.249109 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:29:15.339664 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:29:15.347989 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 22:29:15.369351 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 22:29:15.373240 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:29:15.375590 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:29:15.378586 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:29:15.387734 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Aug 5 22:29:15.437188 kernel: cryptd: max_cpu_qlen set to 1000 Aug 5 22:29:15.437207 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Aug 5 22:29:15.437537 kernel: AVX2 version of gcm_enc/dec engaged. Aug 5 22:29:15.437556 kernel: AES CTR mode by8 optimization enabled Aug 5 22:29:15.437570 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:29:15.396004 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 22:29:15.413632 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:29:15.422328 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:29:15.422534 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:29:15.430365 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:29:15.431833 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:29:15.432045 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:29:15.433591 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:29:15.449134 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:29:15.454769 kernel: libata version 3.00 loaded. Aug 5 22:29:15.459752 kernel: ata_piix 0000:00:01.1: version 2.13 Aug 5 22:29:15.466555 kernel: scsi host0: ata_piix Aug 5 22:29:15.466838 kernel: scsi host1: ata_piix Aug 5 22:29:15.467045 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14 Aug 5 22:29:15.467061 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15 Aug 5 22:29:15.482673 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 5 22:29:15.520135 kernel: BTRFS: device fsid 24d7efdf-5582-42d2-aafd-43221656b08f devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (471) Aug 5 22:29:15.520171 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (459) Aug 5 22:29:15.521916 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:29:15.529998 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 5 22:29:15.549091 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 5 22:29:15.553189 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 5 22:29:15.560929 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:29:15.577001 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 22:29:15.580674 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:29:15.591731 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:29:15.602751 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:29:15.603070 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:29:15.631761 kernel: ata2: found unknown device (class 0) Aug 5 22:29:15.634168 kernel: ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Aug 5 22:29:15.637753 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Aug 5 22:29:15.686645 kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Aug 5 22:29:15.698349 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 5 22:29:15.698377 kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Aug 5 22:29:16.651724 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:29:16.651826 disk-uuid[543]: The operation has completed successfully. Aug 5 22:29:16.682228 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 22:29:16.682354 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 22:29:16.707964 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 22:29:16.711903 sh[579]: Success Aug 5 22:29:16.726729 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Aug 5 22:29:16.764885 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 22:29:16.777410 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 22:29:16.780219 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 22:29:16.801535 kernel: BTRFS info (device dm-0): first mount of filesystem 24d7efdf-5582-42d2-aafd-43221656b08f Aug 5 22:29:16.801567 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:29:16.801580 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 22:29:16.802562 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 22:29:16.803322 kernel: BTRFS info (device dm-0): using free space tree Aug 5 22:29:16.808497 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 22:29:16.809339 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 22:29:16.813893 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 22:29:16.816836 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 22:29:16.828356 kernel: BTRFS info (device vda6): first mount of filesystem b97abe4c-c512-4c9a-9e43-191f8cef484b Aug 5 22:29:16.828410 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:29:16.828427 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:29:16.831737 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:29:16.843208 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 22:29:16.844919 kernel: BTRFS info (device vda6): last unmount of filesystem b97abe4c-c512-4c9a-9e43-191f8cef484b Aug 5 22:29:16.942286 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:29:16.979040 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:29:17.004188 systemd-networkd[757]: lo: Link UP Aug 5 22:29:17.004198 systemd-networkd[757]: lo: Gained carrier Aug 5 22:29:17.005905 systemd-networkd[757]: Enumeration completed Aug 5 22:29:17.006120 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:29:17.006295 systemd-networkd[757]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:29:17.006299 systemd-networkd[757]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:29:17.007588 systemd-networkd[757]: eth0: Link UP Aug 5 22:29:17.007592 systemd-networkd[757]: eth0: Gained carrier Aug 5 22:29:17.007598 systemd-networkd[757]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:29:17.009256 systemd[1]: Reached target network.target - Network. Aug 5 22:29:17.028746 systemd-networkd[757]: eth0: DHCPv4 address 10.0.0.99/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:29:17.145266 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 22:29:17.181941 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 22:29:17.290283 ignition[762]: Ignition 2.19.0 Aug 5 22:29:17.290298 ignition[762]: Stage: fetch-offline Aug 5 22:29:17.290339 ignition[762]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:17.290352 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:17.290476 ignition[762]: parsed url from cmdline: "" Aug 5 22:29:17.290481 ignition[762]: no config URL provided Aug 5 22:29:17.290488 ignition[762]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 22:29:17.290498 ignition[762]: no config at "/usr/lib/ignition/user.ign" Aug 5 22:29:17.290543 ignition[762]: op(1): [started] loading QEMU firmware config module Aug 5 22:29:17.290549 ignition[762]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 5 22:29:17.303299 ignition[762]: op(1): [finished] loading QEMU firmware config module Aug 5 22:29:17.322379 ignition[762]: parsing config with SHA512: 94cc1b8f834d982d850caefb905c311eeac721c066f2d56fa8ee572df995ff7b1f4ece45c1441bd146aced6008a082438f65fca4e7b9dcefa9e06a12241260ec Aug 5 22:29:17.331406 unknown[762]: fetched base config from "system" Aug 5 22:29:17.331429 unknown[762]: fetched user config from "qemu" Aug 5 22:29:17.331665 ignition[762]: fetch-offline: fetch-offline passed Aug 5 22:29:17.334553 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:29:17.331790 ignition[762]: Ignition finished successfully Aug 5 22:29:17.336411 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 5 22:29:17.344958 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 22:29:17.377923 ignition[774]: Ignition 2.19.0 Aug 5 22:29:17.379198 ignition[774]: Stage: kargs Aug 5 22:29:17.379473 ignition[774]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:17.379493 ignition[774]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:17.380121 ignition[774]: kargs: kargs passed Aug 5 22:29:17.380173 ignition[774]: Ignition finished successfully Aug 5 22:29:17.383876 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 22:29:17.395916 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 22:29:17.411161 ignition[782]: Ignition 2.19.0 Aug 5 22:29:17.411174 ignition[782]: Stage: disks Aug 5 22:29:17.414491 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 22:29:17.411365 ignition[782]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:17.441115 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 22:29:17.411378 ignition[782]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:17.442819 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 22:29:17.411985 ignition[782]: disks: disks passed Aug 5 22:29:17.445302 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:29:17.412037 ignition[782]: Ignition finished successfully Aug 5 22:29:17.446452 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:29:17.448678 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:29:17.459906 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 22:29:17.475363 systemd-fsck[792]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 5 22:29:17.659453 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 22:29:17.675864 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 22:29:17.811764 kernel: EXT4-fs (vda9): mounted filesystem b6919f21-4a66-43c1-b816-e6fe5d1b75ef r/w with ordered data mode. Quota mode: none. Aug 5 22:29:17.812135 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 22:29:17.822255 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 22:29:17.830813 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:29:17.833049 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 22:29:17.834402 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 22:29:17.834449 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 22:29:17.848750 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (800) Aug 5 22:29:17.848793 kernel: BTRFS info (device vda6): first mount of filesystem b97abe4c-c512-4c9a-9e43-191f8cef484b Aug 5 22:29:17.848810 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:29:17.848825 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:29:17.834473 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:29:17.842951 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 22:29:17.853970 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:29:17.850061 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 22:29:17.856664 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:29:17.908121 initrd-setup-root[824]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 22:29:17.916325 initrd-setup-root[831]: cut: /sysroot/etc/group: No such file or directory Aug 5 22:29:17.921763 initrd-setup-root[838]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 22:29:17.927664 initrd-setup-root[845]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 22:29:18.111448 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 22:29:18.127988 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 22:29:18.130156 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 22:29:18.137041 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 22:29:18.138214 kernel: BTRFS info (device vda6): last unmount of filesystem b97abe4c-c512-4c9a-9e43-191f8cef484b Aug 5 22:29:18.161716 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 22:29:18.356968 systemd-networkd[757]: eth0: Gained IPv6LL Aug 5 22:29:18.413993 ignition[917]: INFO : Ignition 2.19.0 Aug 5 22:29:18.413993 ignition[917]: INFO : Stage: mount Aug 5 22:29:18.416015 ignition[917]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:18.416015 ignition[917]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:18.416015 ignition[917]: INFO : mount: mount passed Aug 5 22:29:18.416015 ignition[917]: INFO : Ignition finished successfully Aug 5 22:29:18.417851 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 22:29:18.456973 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 22:29:18.824914 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:29:18.833730 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (927) Aug 5 22:29:18.835864 kernel: BTRFS info (device vda6): first mount of filesystem b97abe4c-c512-4c9a-9e43-191f8cef484b Aug 5 22:29:18.835883 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:29:18.835894 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:29:18.839721 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:29:18.841069 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:29:18.882934 ignition[944]: INFO : Ignition 2.19.0 Aug 5 22:29:18.882934 ignition[944]: INFO : Stage: files Aug 5 22:29:18.884903 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:18.884903 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:18.887804 ignition[944]: DEBUG : files: compiled without relabeling support, skipping Aug 5 22:29:18.889317 ignition[944]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 22:29:18.889317 ignition[944]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 22:29:18.893322 ignition[944]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 22:29:18.894921 ignition[944]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 22:29:18.894921 ignition[944]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 22:29:18.893969 unknown[944]: wrote ssh authorized keys file for user: core Aug 5 22:29:18.899361 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:29:18.899361 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(4): [started] processing unit "etcd-member.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Aug 5 22:29:18.899361 ignition[944]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Aug 5 22:29:18.926509 ignition[944]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:29:18.933894 ignition[944]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 22:29:18.935786 ignition[944]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Aug 5 22:29:18.935786 ignition[944]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Aug 5 22:29:18.935786 ignition[944]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Aug 5 22:29:18.935786 ignition[944]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:29:18.935786 ignition[944]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:29:18.935786 ignition[944]: INFO : files: files passed Aug 5 22:29:18.935786 ignition[944]: INFO : Ignition finished successfully Aug 5 22:29:18.937727 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 22:29:18.949974 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 22:29:18.952540 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 22:29:18.957908 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 22:29:18.958137 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 22:29:18.965396 initrd-setup-root-after-ignition[972]: grep: /sysroot/oem/oem-release: No such file or directory Aug 5 22:29:18.968646 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:29:18.968646 initrd-setup-root-after-ignition[974]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:29:18.972751 initrd-setup-root-after-ignition[978]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:29:18.977257 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:29:18.979091 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 22:29:18.992013 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 22:29:19.032113 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 22:29:19.032266 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 22:29:19.035563 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 22:29:19.037832 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 22:29:19.040489 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 22:29:19.050024 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 22:29:19.065952 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:29:19.075958 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 22:29:19.087883 systemd[1]: Stopped target network.target - Network. Aug 5 22:29:19.089011 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:29:19.090963 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:29:19.093411 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 22:29:19.095458 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 22:29:19.095647 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:29:19.097999 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 22:29:19.099557 systemd[1]: Stopped target basic.target - Basic System. Aug 5 22:29:19.101739 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 22:29:19.103844 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:29:19.105970 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 22:29:19.108366 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 22:29:19.111064 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:29:19.113817 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 22:29:19.116079 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 22:29:19.118573 systemd[1]: Stopped target swap.target - Swaps. Aug 5 22:29:19.120639 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 22:29:19.120857 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:29:19.123492 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:29:19.125195 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:29:19.127650 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 22:29:19.127902 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:29:19.130259 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 22:29:19.130422 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 22:29:19.133191 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 22:29:19.133349 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:29:19.135510 systemd[1]: Stopped target paths.target - Path Units. Aug 5 22:29:19.137509 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 22:29:19.137822 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:29:19.140503 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 22:29:19.142514 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 22:29:19.144641 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 22:29:19.144811 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:29:19.146837 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 22:29:19.146962 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:29:19.149131 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 22:29:19.149266 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:29:19.151463 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 22:29:19.151618 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 22:29:19.171035 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 22:29:19.174267 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 22:29:19.175621 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 22:29:19.178304 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 22:29:19.180419 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 22:29:19.180589 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:29:19.183173 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 22:29:19.183380 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:29:19.183771 systemd-networkd[757]: eth0: DHCPv6 lease lost Aug 5 22:29:19.191958 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 22:29:19.192144 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 22:29:19.195877 ignition[999]: INFO : Ignition 2.19.0 Aug 5 22:29:19.195877 ignition[999]: INFO : Stage: umount Aug 5 22:29:19.195877 ignition[999]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:29:19.195877 ignition[999]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 22:29:19.195877 ignition[999]: INFO : umount: umount passed Aug 5 22:29:19.195877 ignition[999]: INFO : Ignition finished successfully Aug 5 22:29:19.196949 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 22:29:19.198781 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 22:29:19.208263 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 22:29:19.209008 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 22:29:19.209143 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 22:29:19.212804 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 22:29:19.212984 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 22:29:19.216549 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 22:29:19.216620 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:29:19.217157 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 22:29:19.217218 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 22:29:19.217582 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 22:29:19.217649 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 22:29:19.217940 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 22:29:19.217984 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 22:29:19.218277 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 22:29:19.218320 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 22:29:19.229986 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 22:29:19.231777 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 22:29:19.231863 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:29:19.234470 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 22:29:19.234533 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:29:19.236881 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 22:29:19.236952 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 22:29:19.237963 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 22:29:19.238029 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:29:19.238465 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:29:19.239221 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 22:29:19.239369 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 22:29:19.254254 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 22:29:19.254349 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 22:29:19.261232 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 22:29:19.261417 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 22:29:19.276187 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 22:29:19.276444 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:29:19.279323 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 22:29:19.279385 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 22:29:19.281733 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 22:29:19.281788 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:29:19.284184 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 22:29:19.284253 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:29:19.285898 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 22:29:19.285967 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 22:29:19.292930 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:29:19.292987 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:29:19.304969 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 22:29:19.305099 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 22:29:19.305168 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:29:19.308462 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 5 22:29:19.308580 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:29:19.313435 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 22:29:19.313526 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:29:19.315071 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:29:19.315144 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:29:19.316059 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 22:29:19.316239 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 22:29:19.321854 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 22:29:19.325296 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 22:29:19.340207 systemd[1]: Switching root. Aug 5 22:29:19.375582 systemd-journald[193]: Journal stopped Aug 5 22:29:20.298538 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Aug 5 22:29:20.298634 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 22:29:20.298655 kernel: SELinux: policy capability open_perms=1 Aug 5 22:29:20.298671 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 22:29:20.298709 kernel: SELinux: policy capability always_check_network=0 Aug 5 22:29:20.298730 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 22:29:20.298753 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 22:29:20.298768 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 22:29:20.298784 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 22:29:20.298806 kernel: audit: type=1403 audit(1722896959.472:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 22:29:20.298827 systemd[1]: Successfully loaded SELinux policy in 44.472ms. Aug 5 22:29:20.298854 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 14.802ms. Aug 5 22:29:20.298872 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 5 22:29:20.298889 systemd[1]: Detected virtualization kvm. Aug 5 22:29:20.298906 systemd[1]: Detected architecture x86-64. Aug 5 22:29:20.298923 systemd[1]: Detected first boot. Aug 5 22:29:20.298940 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:29:20.298956 zram_generator::config[1043]: No configuration found. Aug 5 22:29:20.298979 systemd[1]: Populated /etc with preset unit settings. Aug 5 22:29:20.298996 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 5 22:29:20.299013 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 5 22:29:20.299030 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 5 22:29:20.299049 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 22:29:20.299066 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 22:29:20.299092 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 22:29:20.299118 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 22:29:20.299143 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 22:29:20.299161 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 22:29:20.299178 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 22:29:20.299209 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 22:29:20.299244 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:29:20.299292 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:29:20.299335 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 22:29:20.299372 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 22:29:20.299400 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 22:29:20.299418 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:29:20.299435 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 5 22:29:20.299451 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:29:20.299475 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 5 22:29:20.299492 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 5 22:29:20.299509 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 5 22:29:20.299529 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 22:29:20.299546 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:29:20.299576 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:29:20.299594 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:29:20.299611 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:29:20.299627 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 22:29:20.299647 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 22:29:20.299664 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:29:20.299684 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:29:20.299719 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:29:20.299736 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 22:29:20.299754 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 22:29:20.299770 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 22:29:20.299787 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 22:29:20.299803 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:20.299820 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 22:29:20.299836 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 22:29:20.299858 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 22:29:20.299876 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Aug 5 22:29:20.299893 systemd[1]: Reached target machines.target - Containers. Aug 5 22:29:20.299910 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 22:29:20.299928 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:29:20.299946 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:29:20.299964 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 22:29:20.299981 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:29:20.300003 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:29:20.300022 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:29:20.300042 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 22:29:20.300060 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:29:20.300078 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 22:29:20.300097 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 5 22:29:20.300115 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 5 22:29:20.300133 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 5 22:29:20.300156 systemd[1]: Stopped systemd-fsck-usr.service. Aug 5 22:29:20.300173 kernel: loop: module loaded Aug 5 22:29:20.300190 kernel: fuse: init (API version 7.39) Aug 5 22:29:20.300206 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:29:20.300224 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:29:20.300242 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 22:29:20.300285 systemd-journald[1105]: Collecting audit messages is disabled. Aug 5 22:29:20.300319 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 22:29:20.300342 kernel: ACPI: bus type drm_connector registered Aug 5 22:29:20.300360 systemd-journald[1105]: Journal started Aug 5 22:29:20.300390 systemd-journald[1105]: Runtime Journal (/run/log/journal/7cc6d108f3c840f283973aaf9aa0102c) is 6.0M, max 48.4M, 42.3M free. Aug 5 22:29:20.054758 systemd[1]: Queued start job for default target multi-user.target. Aug 5 22:29:20.074596 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 5 22:29:20.075131 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 5 22:29:20.304729 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:29:20.307230 systemd[1]: verity-setup.service: Deactivated successfully. Aug 5 22:29:20.307272 systemd[1]: Stopped verity-setup.service. Aug 5 22:29:20.335796 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:20.343735 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:29:20.344820 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 22:29:20.346191 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 22:29:20.347650 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 22:29:20.348947 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 22:29:20.350364 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 22:29:20.351922 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 22:29:20.353407 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:29:20.355238 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 22:29:20.355424 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 22:29:20.380838 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:29:20.381045 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:29:20.383094 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:29:20.383316 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:29:20.385018 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:29:20.385208 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:29:20.387935 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 22:29:20.388193 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 22:29:20.390098 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:29:20.390342 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:29:20.410402 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:29:20.412491 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 22:29:20.414671 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 22:29:20.439652 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 22:29:20.452859 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 22:29:20.465347 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 22:29:20.466838 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 22:29:20.466891 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:29:20.469482 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 22:29:20.472445 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 22:29:20.475669 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 22:29:20.477177 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:29:20.486978 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 22:29:20.490326 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 22:29:20.491961 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:29:20.494511 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 22:29:20.496392 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:29:20.499129 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:29:20.502068 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 22:29:20.508726 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:29:20.516591 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:29:20.518414 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 22:29:20.520348 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 22:29:20.522319 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 22:29:20.527660 systemd-journald[1105]: Time spent on flushing to /var/log/journal/7cc6d108f3c840f283973aaf9aa0102c is 16.311ms for 924 entries. Aug 5 22:29:20.527660 systemd-journald[1105]: System Journal (/var/log/journal/7cc6d108f3c840f283973aaf9aa0102c) is 8.0M, max 195.6M, 187.6M free. Aug 5 22:29:20.630951 systemd-journald[1105]: Received client request to flush runtime journal. Aug 5 22:29:20.630992 kernel: loop0: detected capacity change from 0 to 80568 Aug 5 22:29:20.631008 kernel: block loop0: the capability attribute has been deprecated. Aug 5 22:29:20.631216 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 22:29:20.538968 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 22:29:20.567356 udevadm[1156]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 5 22:29:20.603499 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:29:20.611822 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 22:29:20.615031 systemd-tmpfiles[1149]: ACLs are not supported, ignoring. Aug 5 22:29:20.615042 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 22:29:20.615050 systemd-tmpfiles[1149]: ACLs are not supported, ignoring. Aug 5 22:29:20.626945 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 22:29:20.628909 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 22:29:20.633775 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 22:29:20.636551 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:29:20.650033 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 22:29:20.659251 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 22:29:20.660629 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 22:29:20.662830 kernel: loop1: detected capacity change from 0 to 139760 Aug 5 22:29:20.690197 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 22:29:20.703961 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:29:20.705183 kernel: loop2: detected capacity change from 0 to 80568 Aug 5 22:29:20.717853 kernel: loop3: detected capacity change from 0 to 139760 Aug 5 22:29:20.730769 (sd-merge)[1180]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Aug 5 22:29:20.732743 (sd-merge)[1180]: Merged extensions into '/usr'. Aug 5 22:29:20.733140 systemd-tmpfiles[1179]: ACLs are not supported, ignoring. Aug 5 22:29:20.733161 systemd-tmpfiles[1179]: ACLs are not supported, ignoring. Aug 5 22:29:20.741948 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:29:20.744604 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 22:29:20.756959 systemd[1]: Starting ensure-sysext.service... Aug 5 22:29:20.760542 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:29:20.771103 systemd[1]: Reloading requested from client PID 1184 ('systemctl') (unit ensure-sysext.service)... Aug 5 22:29:20.771288 systemd[1]: Reloading... Aug 5 22:29:20.921951 zram_generator::config[1211]: No configuration found. Aug 5 22:29:20.944109 systemd-tmpfiles[1185]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 22:29:20.944474 systemd-tmpfiles[1185]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 22:29:20.945449 systemd-tmpfiles[1185]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 22:29:20.945796 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. Aug 5 22:29:20.946069 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. Aug 5 22:29:20.953793 systemd-tmpfiles[1185]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:29:20.953901 systemd-tmpfiles[1185]: Skipping /boot Aug 5 22:29:20.968589 systemd-tmpfiles[1185]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:29:20.968603 systemd-tmpfiles[1185]: Skipping /boot Aug 5 22:29:21.028121 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 5 22:29:21.080278 systemd[1]: Reloading finished in 308 ms. Aug 5 22:29:21.109195 ldconfig[1136]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 22:29:21.112767 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:29:21.130328 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 22:29:21.162435 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 22:29:21.166607 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 22:29:21.172867 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:29:21.186033 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 22:29:21.194812 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.195102 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:29:21.206073 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:29:21.213177 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:29:21.223256 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:29:21.225964 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:29:21.229616 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 22:29:21.230898 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.232388 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 22:29:21.234367 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:29:21.234595 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:29:21.236758 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:29:21.237013 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:29:21.239619 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:29:21.239948 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:29:21.247867 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 22:29:21.262568 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.263180 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:29:21.266430 augenrules[1278]: No rules Aug 5 22:29:21.273485 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:29:21.278313 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:29:21.282978 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:29:21.284294 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:29:21.284402 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.285495 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 22:29:21.289353 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 22:29:21.291858 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:29:21.292064 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:29:21.324102 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 22:29:21.328151 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.328426 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:29:21.332082 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:29:21.336418 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:29:21.337656 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:29:21.337850 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Aug 5 22:29:21.337933 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:29:21.338657 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 22:29:21.340480 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:29:21.340794 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:29:21.342886 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:29:21.343760 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:29:21.352923 systemd[1]: Finished ensure-sysext.service. Aug 5 22:29:21.356275 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:29:21.356571 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:29:21.362356 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:29:21.362675 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:29:21.365855 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:29:21.366011 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:29:21.379202 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 5 22:29:21.438931 systemd-resolved[1254]: Positive Trust Anchors: Aug 5 22:29:21.438959 systemd-resolved[1254]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:29:21.438998 systemd-resolved[1254]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 22:29:21.447512 systemd-resolved[1254]: Defaulting to hostname 'linux'. Aug 5 22:29:21.451585 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:29:21.453374 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:29:21.520929 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 5 22:29:21.522909 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 22:29:21.625317 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 22:29:21.641072 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:29:21.651751 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 22:29:21.677889 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 22:29:21.678371 systemd-udevd[1305]: Using default interface naming scheme 'v255'. Aug 5 22:29:21.730199 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:29:21.753291 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:29:21.822608 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 5 22:29:21.824909 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1322) Aug 5 22:29:21.882117 systemd-networkd[1313]: lo: Link UP Aug 5 22:29:21.882137 systemd-networkd[1313]: lo: Gained carrier Aug 5 22:29:21.885363 systemd-networkd[1313]: Enumeration completed Aug 5 22:29:21.886085 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:29:21.887174 systemd-networkd[1313]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:29:21.887179 systemd-networkd[1313]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:29:21.887895 systemd[1]: Reached target network.target - Network. Aug 5 22:29:21.892498 systemd-networkd[1313]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:29:21.892543 systemd-networkd[1313]: eth0: Link UP Aug 5 22:29:21.892549 systemd-networkd[1313]: eth0: Gained carrier Aug 5 22:29:21.892563 systemd-networkd[1313]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:29:21.908391 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Aug 5 22:29:21.907992 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 22:29:21.910719 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Aug 5 22:29:21.916783 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1320) Aug 5 22:29:21.918732 kernel: ACPI: button: Power Button [PWRF] Aug 5 22:29:21.919822 systemd-networkd[1313]: eth0: DHCPv4 address 10.0.0.99/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 22:29:21.920872 systemd-timesyncd[1302]: Network configuration changed, trying to establish connection. Aug 5 22:29:22.903657 systemd-timesyncd[1302]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 5 22:29:22.903724 systemd-timesyncd[1302]: Initial clock synchronization to Mon 2024-08-05 22:29:22.903526 UTC. Aug 5 22:29:22.903897 systemd-resolved[1254]: Clock change detected. Flushing caches. Aug 5 22:29:22.936829 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Aug 5 22:29:22.973499 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:29:22.983989 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 22:29:23.008772 kernel: mousedev: PS/2 mouse device common for all mice Aug 5 22:29:23.018218 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:29:23.020235 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 22:29:23.107274 kernel: kvm_amd: TSC scaling supported Aug 5 22:29:23.107364 kernel: kvm_amd: Nested Virtualization enabled Aug 5 22:29:23.107379 kernel: kvm_amd: Nested Paging enabled Aug 5 22:29:23.109153 kernel: kvm_amd: LBR virtualization supported Aug 5 22:29:23.109188 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Aug 5 22:29:23.109202 kernel: kvm_amd: Virtual GIF supported Aug 5 22:29:23.134750 kernel: EDAC MC: Ver: 3.0.0 Aug 5 22:29:23.174073 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:29:23.189185 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 22:29:23.205138 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 22:29:23.214664 lvm[1352]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:29:23.262690 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 22:29:23.264428 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:29:23.265692 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:29:23.266974 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 22:29:23.268365 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 22:29:23.269954 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 22:29:23.271354 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 22:29:23.272736 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 22:29:23.274337 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 22:29:23.274367 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:29:23.275345 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:29:23.277349 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 22:29:23.280552 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 22:29:23.294110 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 22:29:23.296793 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 22:29:23.298813 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 22:29:23.300376 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:29:23.301628 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:29:23.302902 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:29:23.302942 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:29:23.304442 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 22:29:23.307250 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 22:29:23.310217 lvm[1356]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:29:23.310892 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 22:29:23.314255 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 22:29:23.315617 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Aug 5 22:29:23.319875 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 22:29:23.324859 jq[1359]: false Aug 5 22:29:23.326068 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 22:29:23.329944 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 22:29:23.339542 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 22:29:23.342645 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 22:29:23.343480 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 22:29:23.346558 extend-filesystems[1360]: Found loop2 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found loop3 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found sr0 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda1 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda2 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda3 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found usr Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda4 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda6 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda7 Aug 5 22:29:23.347009 extend-filesystems[1360]: Found vda9 Aug 5 22:29:23.347009 extend-filesystems[1360]: Checking size of /dev/vda9 Aug 5 22:29:23.359319 dbus-daemon[1358]: [system] SELinux support is enabled Aug 5 22:29:23.364999 extend-filesystems[1360]: Old size kept for /dev/vda9 Aug 5 22:29:23.363452 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 22:29:23.369319 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 22:29:23.375853 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 22:29:23.380704 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 22:29:23.381359 jq[1376]: true Aug 5 22:29:23.387785 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1314) Aug 5 22:29:23.396640 update_engine[1368]: I0805 22:29:23.396540 1368 main.cc:92] Flatcar Update Engine starting Aug 5 22:29:23.399172 update_engine[1368]: I0805 22:29:23.398239 1368 update_check_scheduler.cc:74] Next update check in 8m25s Aug 5 22:29:23.399085 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 22:29:23.399381 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 22:29:23.399881 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 22:29:23.400152 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 22:29:23.402246 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 22:29:23.402536 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 22:29:23.404355 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 22:29:23.404644 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 22:29:23.423001 jq[1382]: true Aug 5 22:29:23.424817 (ntainerd)[1383]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 22:29:23.437793 systemd-logind[1366]: Watching system buttons on /dev/input/event1 (Power Button) Aug 5 22:29:23.437857 systemd-logind[1366]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 5 22:29:23.438883 systemd-logind[1366]: New seat seat0. Aug 5 22:29:23.452302 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 22:29:23.464302 dbus-daemon[1358]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 5 22:29:23.474354 systemd[1]: Started update-engine.service - Update Engine. Aug 5 22:29:23.478326 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Aug 5 22:29:23.478573 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 22:29:23.480378 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Aug 5 22:29:23.480571 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 22:29:23.490079 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 22:29:23.520433 bash[1408]: Updated "/home/core/.ssh/authorized_keys" Aug 5 22:29:23.546925 sshd_keygen[1381]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 22:29:23.547265 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 22:29:23.551834 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 5 22:29:23.617393 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 22:29:23.622856 locksmithd[1404]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 5 22:29:23.626045 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 22:29:23.640123 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 22:29:23.640427 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 22:29:23.645261 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 22:29:23.689014 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 22:29:23.700268 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 22:29:23.715743 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 5 22:29:23.717536 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 22:29:23.980869 containerd[1383]: time="2024-08-05T22:29:23.980707736Z" level=info msg="starting containerd" revision=cd7148ac666309abf41fd4a49a8a5895b905e7f3 version=v1.7.18 Aug 5 22:29:24.010107 containerd[1383]: time="2024-08-05T22:29:24.010013755Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 22:29:24.010107 containerd[1383]: time="2024-08-05T22:29:24.010089287Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.012554 containerd[1383]: time="2024-08-05T22:29:24.012489298Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:29:24.012554 containerd[1383]: time="2024-08-05T22:29:24.012532990Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.012926 containerd[1383]: time="2024-08-05T22:29:24.012887815Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:29:24.012926 containerd[1383]: time="2024-08-05T22:29:24.012913243Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 22:29:24.013055 containerd[1383]: time="2024-08-05T22:29:24.013035833Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013138 containerd[1383]: time="2024-08-05T22:29:24.013119750Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013138 containerd[1383]: time="2024-08-05T22:29:24.013135760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013269 containerd[1383]: time="2024-08-05T22:29:24.013252499Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013586 containerd[1383]: time="2024-08-05T22:29:24.013558363Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013586 containerd[1383]: time="2024-08-05T22:29:24.013581887Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 22:29:24.013629 containerd[1383]: time="2024-08-05T22:29:24.013592216Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013782 containerd[1383]: time="2024-08-05T22:29:24.013746576Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:29:24.013782 containerd[1383]: time="2024-08-05T22:29:24.013767064Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 22:29:24.013861 containerd[1383]: time="2024-08-05T22:29:24.013848707Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 22:29:24.013896 containerd[1383]: time="2024-08-05T22:29:24.013861982Z" level=info msg="metadata content store policy set" policy=shared Aug 5 22:29:24.040241 containerd[1383]: time="2024-08-05T22:29:24.040134352Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 22:29:24.040241 containerd[1383]: time="2024-08-05T22:29:24.040195336Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 22:29:24.040241 containerd[1383]: time="2024-08-05T22:29:24.040211877Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 22:29:24.040241 containerd[1383]: time="2024-08-05T22:29:24.040273774Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 22:29:24.040573 containerd[1383]: time="2024-08-05T22:29:24.040291667Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 22:29:24.040573 containerd[1383]: time="2024-08-05T22:29:24.040308388Z" level=info msg="NRI interface is disabled by configuration." Aug 5 22:29:24.040573 containerd[1383]: time="2024-08-05T22:29:24.040327895Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 22:29:24.040573 containerd[1383]: time="2024-08-05T22:29:24.040557456Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 22:29:24.040573 containerd[1383]: time="2024-08-05T22:29:24.040578305Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040594425Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040608231Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040624361Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040642986Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040663034Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040682620Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040699282Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040736802Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.040758 containerd[1383]: time="2024-08-05T22:29:24.040758052Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.041014 containerd[1383]: time="2024-08-05T22:29:24.040772990Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 22:29:24.041014 containerd[1383]: time="2024-08-05T22:29:24.040916689Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 22:29:24.041284 containerd[1383]: time="2024-08-05T22:29:24.041241228Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 22:29:24.041330 containerd[1383]: time="2024-08-05T22:29:24.041284990Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041330 containerd[1383]: time="2024-08-05T22:29:24.041312732Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 22:29:24.041409 containerd[1383]: time="2024-08-05T22:29:24.041340504Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 22:29:24.041445 containerd[1383]: time="2024-08-05T22:29:24.041414733Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041445 containerd[1383]: time="2024-08-05T22:29:24.041429501Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041445 containerd[1383]: time="2024-08-05T22:29:24.041441443Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041453897Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041471329Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041486147Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041512276Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041530420Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041546 containerd[1383]: time="2024-08-05T22:29:24.041548745Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041771823Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041795277Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041809634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041821757Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041834150Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.041849 containerd[1383]: time="2024-08-05T22:29:24.041848827Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.042032 containerd[1383]: time="2024-08-05T22:29:24.041861591Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.042032 containerd[1383]: time="2024-08-05T22:29:24.041872752Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 22:29:24.042285 containerd[1383]: time="2024-08-05T22:29:24.042205656Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 5 22:29:24.042285 containerd[1383]: time="2024-08-05T22:29:24.042263986Z" level=info msg="Connect containerd service" Aug 5 22:29:24.042285 containerd[1383]: time="2024-08-05T22:29:24.042296667Z" level=info msg="using legacy CRI server" Aug 5 22:29:24.042658 containerd[1383]: time="2024-08-05T22:29:24.042306516Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 22:29:24.042658 containerd[1383]: time="2024-08-05T22:29:24.042423525Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 22:29:24.043247 containerd[1383]: time="2024-08-05T22:29:24.043193299Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Aug 5 22:29:24.043247 containerd[1383]: time="2024-08-05T22:29:24.043245326Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 22:29:24.043351 containerd[1383]: time="2024-08-05T22:29:24.043264232Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Aug 5 22:29:24.043351 containerd[1383]: time="2024-08-05T22:29:24.043278358Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 22:29:24.043351 containerd[1383]: time="2024-08-05T22:29:24.043300941Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 22:29:24.043544 containerd[1383]: time="2024-08-05T22:29:24.043423851Z" level=info msg="Start subscribing containerd event" Aug 5 22:29:24.043589 containerd[1383]: time="2024-08-05T22:29:24.043549316Z" level=info msg="Start recovering state" Aug 5 22:29:24.043798 containerd[1383]: time="2024-08-05T22:29:24.043755263Z" level=info msg="Start event monitor" Aug 5 22:29:24.043862 containerd[1383]: time="2024-08-05T22:29:24.043768237Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 22:29:24.043862 containerd[1383]: time="2024-08-05T22:29:24.043856001Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 22:29:24.043966 containerd[1383]: time="2024-08-05T22:29:24.043802050Z" level=info msg="Start snapshots syncer" Aug 5 22:29:24.044024 containerd[1383]: time="2024-08-05T22:29:24.043997146Z" level=info msg="Start cni network conf syncer for default" Aug 5 22:29:24.044024 containerd[1383]: time="2024-08-05T22:29:24.044020310Z" level=info msg="Start streaming server" Aug 5 22:29:24.044561 containerd[1383]: time="2024-08-05T22:29:24.044522281Z" level=info msg="containerd successfully booted in 0.066764s" Aug 5 22:29:24.044820 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 22:29:24.777991 systemd-networkd[1313]: eth0: Gained IPv6LL Aug 5 22:29:24.781918 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 22:29:24.784172 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 22:29:24.796047 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 22:29:24.799574 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 22:29:24.835450 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 22:29:24.839570 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 22:29:24.839883 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 22:29:24.852406 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Aug 5 22:29:24.854092 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 22:29:24.854191 (-wrapper)[1454]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Aug 5 22:29:25.250129 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 5 22:29:25.255439 (dockerd)[1466]: 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 Aug 5 22:29:25.894586 dockerd[1466]: time="2024-08-05T22:29:25.894485021Z" level=info msg="Starting up" Aug 5 22:29:25.977858 dockerd[1466]: time="2024-08-05T22:29:25.977761825Z" level=info msg="Loading containers: start." Aug 5 22:29:26.128748 kernel: Initializing XFRM netlink socket Aug 5 22:29:26.320625 systemd-networkd[1313]: docker0: Link UP Aug 5 22:29:26.351170 dockerd[1466]: time="2024-08-05T22:29:26.351114344Z" level=info msg="Loading containers: done." Aug 5 22:29:26.504293 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1352205084-merged.mount: Deactivated successfully. Aug 5 22:29:26.508532 dockerd[1466]: time="2024-08-05T22:29:26.508459994Z" 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 Aug 5 22:29:26.508813 dockerd[1466]: time="2024-08-05T22:29:26.508775996Z" level=info msg="Docker daemon" commit=fca702de7f71362c8d103073c7e4a1d0a467fadd graphdriver=overlay2 version=24.0.9 Aug 5 22:29:26.508989 dockerd[1466]: time="2024-08-05T22:29:26.508954581Z" level=info msg="Daemon has completed initialization" Aug 5 22:29:26.557044 dockerd[1466]: time="2024-08-05T22:29:26.556961792Z" level=info msg="API listen on /run/docker.sock" Aug 5 22:29:26.557188 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 5 22:29:26.558753 etcd-wrapper[1459]: Error response from daemon: No such container: etcd-member Aug 5 22:29:26.582334 etcd-wrapper[1581]: Error response from daemon: No such container: etcd-member Aug 5 22:29:26.618306 etcd-wrapper[1600]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally Aug 5 22:29:28.140116 etcd-wrapper[1600]: v3.5.0: Pulling from coreos/etcd Aug 5 22:29:28.681748 etcd-wrapper[1600]: 1813d21adc01: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 6e96907ab677: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 444ed0ea8673: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 0fd2df5633f0: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 8cc22b9456bb: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 7ac70aecd290: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 4b376c64dfe4: Pulling fs layer Aug 5 22:29:28.682120 etcd-wrapper[1600]: 0fd2df5633f0: Waiting Aug 5 22:29:28.682120 etcd-wrapper[1600]: 8cc22b9456bb: Waiting Aug 5 22:29:28.682120 etcd-wrapper[1600]: 7ac70aecd290: Waiting Aug 5 22:29:28.682120 etcd-wrapper[1600]: 4b376c64dfe4: Waiting Aug 5 22:29:28.831630 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 22:29:28.841015 systemd[1]: Started sshd@0-10.0.0.99:22-10.0.0.1:55206.service - OpenSSH per-connection server daemon (10.0.0.1:55206). Aug 5 22:29:28.887158 sshd[1614]: Accepted publickey for core from 10.0.0.1 port 55206 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:28.889536 sshd[1614]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:28.900550 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 22:29:28.911078 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 22:29:28.931311 systemd-logind[1366]: New session 1 of user core. Aug 5 22:29:28.936549 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 22:29:28.948318 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 22:29:28.952798 (systemd)[1618]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:29.040190 etcd-wrapper[1600]: 444ed0ea8673: Verifying Checksum Aug 5 22:29:29.040190 etcd-wrapper[1600]: 444ed0ea8673: Download complete Aug 5 22:29:29.099900 etcd-wrapper[1600]: 6e96907ab677: Verifying Checksum Aug 5 22:29:29.100032 etcd-wrapper[1600]: 6e96907ab677: Download complete Aug 5 22:29:29.102415 systemd[1618]: Queued start job for default target default.target. Aug 5 22:29:29.123429 systemd[1618]: Created slice app.slice - User Application Slice. Aug 5 22:29:29.123463 systemd[1618]: Reached target paths.target - Paths. Aug 5 22:29:29.123478 systemd[1618]: Reached target timers.target - Timers. Aug 5 22:29:29.125640 systemd[1618]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 22:29:29.158199 systemd[1618]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 22:29:29.158646 systemd[1618]: Reached target sockets.target - Sockets. Aug 5 22:29:29.158671 systemd[1618]: Reached target basic.target - Basic System. Aug 5 22:29:29.158749 systemd[1618]: Reached target default.target - Main User Target. Aug 5 22:29:29.158793 systemd[1618]: Startup finished in 196ms. Aug 5 22:29:29.158905 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 22:29:29.174093 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 22:29:29.280112 etcd-wrapper[1600]: 1813d21adc01: Verifying Checksum Aug 5 22:29:29.280112 etcd-wrapper[1600]: 1813d21adc01: Download complete Aug 5 22:29:29.284186 systemd[1]: Started sshd@1-10.0.0.99:22-10.0.0.1:55210.service - OpenSSH per-connection server daemon (10.0.0.1:55210). Aug 5 22:29:29.329569 sshd[1629]: Accepted publickey for core from 10.0.0.1 port 55210 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:29.331946 sshd[1629]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:29.336836 systemd-logind[1366]: New session 2 of user core. Aug 5 22:29:29.342927 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 22:29:29.437369 sshd[1629]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:29.444386 systemd[1]: sshd@1-10.0.0.99:22-10.0.0.1:55210.service: Deactivated successfully. Aug 5 22:29:29.448496 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 22:29:29.449481 systemd-logind[1366]: Session 2 logged out. Waiting for processes to exit. Aug 5 22:29:29.451506 etcd-wrapper[1600]: 8cc22b9456bb: Verifying Checksum Aug 5 22:29:29.451680 etcd-wrapper[1600]: 8cc22b9456bb: Download complete Aug 5 22:29:29.464270 systemd[1]: Started sshd@2-10.0.0.99:22-10.0.0.1:55214.service - OpenSSH per-connection server daemon (10.0.0.1:55214). Aug 5 22:29:29.466866 etcd-wrapper[1600]: 0fd2df5633f0: Verifying Checksum Aug 5 22:29:29.466890 systemd-logind[1366]: Removed session 2. Aug 5 22:29:29.467487 etcd-wrapper[1600]: 0fd2df5633f0: Download complete Aug 5 22:29:29.507827 sshd[1643]: Accepted publickey for core from 10.0.0.1 port 55214 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:29.544424 sshd[1643]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:29.549887 systemd-logind[1366]: New session 3 of user core. Aug 5 22:29:29.554856 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 22:29:29.586024 etcd-wrapper[1600]: 7ac70aecd290: Verifying Checksum Aug 5 22:29:29.586024 etcd-wrapper[1600]: 7ac70aecd290: Download complete Aug 5 22:29:29.613847 sshd[1643]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:29.619359 systemd[1]: sshd@2-10.0.0.99:22-10.0.0.1:55214.service: Deactivated successfully. Aug 5 22:29:29.621498 systemd[1]: session-3.scope: Deactivated successfully. Aug 5 22:29:29.622268 systemd-logind[1366]: Session 3 logged out. Waiting for processes to exit. Aug 5 22:29:29.623731 systemd-logind[1366]: Removed session 3. Aug 5 22:29:29.805573 etcd-wrapper[1600]: 4b376c64dfe4: Verifying Checksum Aug 5 22:29:29.805573 etcd-wrapper[1600]: 4b376c64dfe4: Download complete Aug 5 22:29:30.278536 etcd-wrapper[1600]: 1813d21adc01: Pull complete Aug 5 22:29:30.437091 systemd[1]: var-lib-docker-overlay2-655e89c8a61837c5f2154e33c1ff498959f6f3c006337b65de35bc6b15ae20c2-merged.mount: Deactivated successfully. Aug 5 22:29:30.991610 etcd-wrapper[1600]: 6e96907ab677: Pull complete Aug 5 22:29:31.106206 systemd[1]: var-lib-docker-overlay2-e28f3eb701b439e49ef5b6cfed156f0bd3269b3e3c76a770363a2f955cbd4de6-merged.mount: Deactivated successfully. Aug 5 22:29:31.189976 etcd-wrapper[1600]: 444ed0ea8673: Pull complete Aug 5 22:29:31.336956 systemd[1]: var-lib-docker-overlay2-cd112327ed4df560f9b895d1bcad167fa98a074831361b39f8af50239832bf44-merged.mount: Deactivated successfully. Aug 5 22:29:31.408750 etcd-wrapper[1600]: 0fd2df5633f0: Pull complete Aug 5 22:29:31.428786 systemd[1]: var-lib-docker-overlay2-25d35eabd618d40cae799567e0aa240c160158b47462c1bff248b3990583dc18-merged.mount: Deactivated successfully. Aug 5 22:29:31.444387 etcd-wrapper[1600]: 8cc22b9456bb: Pull complete Aug 5 22:29:31.461785 systemd[1]: var-lib-docker-overlay2-9bb7e23c37833d74ef680ae1246d1b0ba60a598d4631ebb44126884851bd3f57-merged.mount: Deactivated successfully. Aug 5 22:29:31.474881 etcd-wrapper[1600]: 7ac70aecd290: Pull complete Aug 5 22:29:31.509809 etcd-wrapper[1600]: 4b376c64dfe4: Pull complete Aug 5 22:29:31.527534 etcd-wrapper[1600]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b Aug 5 22:29:31.531446 etcd-wrapper[1600]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0 Aug 5 22:29:31.749002 containerd[1383]: time="2024-08-05T22:29:31.748838865Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:29:31.749002 containerd[1383]: time="2024-08-05T22:29:31.748942269Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:29:31.749002 containerd[1383]: time="2024-08-05T22:29:31.748962827Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:29:31.749002 containerd[1383]: time="2024-08-05T22:29:31.748975621Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:29:31.867065 systemd[1]: Started docker-0e30e9446dc92b7b2e04f1a41c3924d19e6b8ad1eb6ddad1f3cb6b26eb2cdc59.scope - libcontainer container 0e30e9446dc92b7b2e04f1a41c3924d19e6b8ad1eb6ddad1f3cb6b26eb2cdc59. Aug 5 22:29:31.886952 systemd-resolved[1254]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"info","ts":1722896971.9639137,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"info","ts":1722896971.9640098,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"7cc6d108f3c840f283973aaf9aa0102c"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"warn","ts":1722896971.9640346,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"warn","ts":1722896971.9640417,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"warn","ts":1722896971.9640472,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"warn","ts":1722896971.964052,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:31.964Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.99:2379"]} Aug 5 22:29:31.964441 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:31.964Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Aug 5 22:29:31.965105 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:31.964Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Aug 5 22:29:31.965238 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:31.965Z","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":4,"max-cpu-available":4,"member-initialized":false,"name":"7cc6d108f3c840f283973aaf9aa0102c","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://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.99:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"7cc6d108f3c840f283973aaf9aa0102c=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","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":"","discovery-proxy":"","downgrade-check-interval":"5s"} Aug 5 22:29:32.133102 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"167.213475ms"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Aug 5 22:29:32.297706 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Aug 5 22:29:32.299688 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:29:32.299Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Aug 5 22:29:32.301760 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.301Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Aug 5 22:29:32.303323 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.303Z","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"} Aug 5 22:29:32.304910 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.304Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Aug 5 22:29:32.304910 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.304Z","caller":"etcdserver/server.go:728","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Aug 5 22:29:32.306549 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Aug 5 22:29:32.306745 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.306Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Aug 5 22:29:32.308350 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.308Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.99:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Aug 5 22:29:32.308350 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.308Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"127.0.0.1:2380"} Aug 5 22:29:32.308350 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:32.308Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"127.0.0.1:2380"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Aug 5 22:29:33.098362 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Aug 5 22:29:33.098922 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:7cc6d108f3c840f283973aaf9aa0102c ClientURLs:[http://10.0.0.99:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Aug 5 22:29:33.098922 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Aug 5 22:29:33.098922 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Aug 5 22:29:33.099190 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.098Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Aug 5 22:29:33.099393 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.099Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Aug 5 22:29:33.099432 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.099Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Aug 5 22:29:33.099556 systemd[1]: Started etcd-member.service - etcd (System Application Container). Aug 5 22:29:33.099922 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 22:29:33.100124 systemd[1]: Startup finished in 1.941s (kernel) + 5.600s (initrd) + 12.689s (userspace) = 20.231s. Aug 5 22:29:33.145113 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.144Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Aug 5 22:29:33.215448 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Aug 5 22:29:33.215448 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:29:33.215Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"} Aug 5 22:29:39.625338 systemd[1]: Started sshd@3-10.0.0.99:22-10.0.0.1:50768.service - OpenSSH per-connection server daemon (10.0.0.1:50768). Aug 5 22:29:39.665602 sshd[1728]: Accepted publickey for core from 10.0.0.1 port 50768 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:39.667425 sshd[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:39.672728 systemd-logind[1366]: New session 4 of user core. Aug 5 22:29:39.689009 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 22:29:39.747005 sshd[1728]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:39.757010 systemd[1]: sshd@3-10.0.0.99:22-10.0.0.1:50768.service: Deactivated successfully. Aug 5 22:29:39.759184 systemd[1]: session-4.scope: Deactivated successfully. Aug 5 22:29:39.760931 systemd-logind[1366]: Session 4 logged out. Waiting for processes to exit. Aug 5 22:29:39.771179 systemd[1]: Started sshd@4-10.0.0.99:22-10.0.0.1:50784.service - OpenSSH per-connection server daemon (10.0.0.1:50784). Aug 5 22:29:39.772559 systemd-logind[1366]: Removed session 4. Aug 5 22:29:39.806321 sshd[1735]: Accepted publickey for core from 10.0.0.1 port 50784 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:39.808412 sshd[1735]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:39.813695 systemd-logind[1366]: New session 5 of user core. Aug 5 22:29:39.825040 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 22:29:39.877971 sshd[1735]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:39.889558 systemd[1]: sshd@4-10.0.0.99:22-10.0.0.1:50784.service: Deactivated successfully. Aug 5 22:29:39.891478 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 22:29:39.893366 systemd-logind[1366]: Session 5 logged out. Waiting for processes to exit. Aug 5 22:29:39.894775 systemd[1]: Started sshd@5-10.0.0.99:22-10.0.0.1:50794.service - OpenSSH per-connection server daemon (10.0.0.1:50794). Aug 5 22:29:39.895771 systemd-logind[1366]: Removed session 5. Aug 5 22:29:39.935673 sshd[1742]: Accepted publickey for core from 10.0.0.1 port 50794 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:39.937433 sshd[1742]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:39.941866 systemd-logind[1366]: New session 6 of user core. Aug 5 22:29:39.958931 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 5 22:29:40.022345 sshd[1742]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:40.032941 systemd[1]: sshd@5-10.0.0.99:22-10.0.0.1:50794.service: Deactivated successfully. Aug 5 22:29:40.035535 systemd[1]: session-6.scope: Deactivated successfully. Aug 5 22:29:40.037998 systemd-logind[1366]: Session 6 logged out. Waiting for processes to exit. Aug 5 22:29:40.050320 systemd[1]: Started sshd@6-10.0.0.99:22-10.0.0.1:50796.service - OpenSSH per-connection server daemon (10.0.0.1:50796). Aug 5 22:29:40.051523 systemd-logind[1366]: Removed session 6. Aug 5 22:29:40.087743 sshd[1749]: Accepted publickey for core from 10.0.0.1 port 50796 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:40.089787 sshd[1749]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:40.094551 systemd-logind[1366]: New session 7 of user core. Aug 5 22:29:40.106055 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 5 22:29:40.168242 sudo[1753]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Aug 5 22:29:40.168558 sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:29:40.187560 sudo[1753]: pam_unix(sudo:session): session closed for user root Aug 5 22:29:40.189820 sshd[1749]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:40.203041 systemd[1]: sshd@6-10.0.0.99:22-10.0.0.1:50796.service: Deactivated successfully. Aug 5 22:29:40.205302 systemd[1]: session-7.scope: Deactivated successfully. Aug 5 22:29:40.206846 systemd-logind[1366]: Session 7 logged out. Waiting for processes to exit. Aug 5 22:29:40.217240 systemd[1]: Started sshd@7-10.0.0.99:22-10.0.0.1:50798.service - OpenSSH per-connection server daemon (10.0.0.1:50798). Aug 5 22:29:40.219003 systemd-logind[1366]: Removed session 7. Aug 5 22:29:40.255191 sshd[1758]: Accepted publickey for core from 10.0.0.1 port 50798 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:40.257129 sshd[1758]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:40.261749 systemd-logind[1366]: New session 8 of user core. Aug 5 22:29:40.271958 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 5 22:29:40.328931 sudo[1762]: 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 Aug 5 22:29:40.329256 sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:29:40.333617 sudo[1762]: pam_unix(sudo:session): session closed for user root Aug 5 22:29:40.341284 sudo[1761]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Aug 5 22:29:40.341607 sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:29:40.366075 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Aug 5 22:29:40.368867 auditctl[1765]: No rules Aug 5 22:29:40.370470 systemd[1]: audit-rules.service: Deactivated successfully. Aug 5 22:29:40.370791 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Aug 5 22:29:40.372839 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 22:29:40.410493 augenrules[1783]: No rules Aug 5 22:29:40.411697 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 22:29:40.413438 sudo[1761]: pam_unix(sudo:session): session closed for user root Aug 5 22:29:40.415440 sshd[1758]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:40.427309 systemd[1]: sshd@7-10.0.0.99:22-10.0.0.1:50798.service: Deactivated successfully. Aug 5 22:29:40.429855 systemd[1]: session-8.scope: Deactivated successfully. Aug 5 22:29:40.431659 systemd-logind[1366]: Session 8 logged out. Waiting for processes to exit. Aug 5 22:29:40.441336 systemd[1]: Started sshd@8-10.0.0.99:22-10.0.0.1:50812.service - OpenSSH per-connection server daemon (10.0.0.1:50812). Aug 5 22:29:40.442550 systemd-logind[1366]: Removed session 8. Aug 5 22:29:40.479497 sshd[1791]: Accepted publickey for core from 10.0.0.1 port 50812 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:40.481659 sshd[1791]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:40.487353 systemd-logind[1366]: New session 9 of user core. Aug 5 22:29:40.496896 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 5 22:29:40.549977 sshd[1791]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:40.566284 systemd[1]: sshd@8-10.0.0.99:22-10.0.0.1:50812.service: Deactivated successfully. Aug 5 22:29:40.568494 systemd[1]: session-9.scope: Deactivated successfully. Aug 5 22:29:40.570137 systemd-logind[1366]: Session 9 logged out. Waiting for processes to exit. Aug 5 22:29:40.571504 systemd[1]: Started sshd@9-10.0.0.99:22-10.0.0.1:38966.service - OpenSSH per-connection server daemon (10.0.0.1:38966). Aug 5 22:29:40.572442 systemd-logind[1366]: Removed session 9. Aug 5 22:29:40.613010 sshd[1800]: Accepted publickey for core from 10.0.0.1 port 38966 ssh2: RSA SHA256:ptvpYoWJLxritDvuuuq7wnHVeQD0cFOU3CO7OKKv9QY Aug 5 22:29:40.614844 sshd[1800]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:29:40.619704 systemd-logind[1366]: New session 10 of user core. Aug 5 22:29:40.636039 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 5 22:29:40.748426 sshd[1800]: pam_unix(sshd:session): session closed for user core Aug 5 22:29:40.753407 systemd[1]: sshd@9-10.0.0.99:22-10.0.0.1:38966.service: Deactivated successfully. Aug 5 22:29:40.755535 systemd[1]: session-10.scope: Deactivated successfully. Aug 5 22:29:40.756284 systemd-logind[1366]: Session 10 logged out. Waiting for processes to exit. Aug 5 22:29:40.757481 systemd-logind[1366]: Removed session 10. Aug 5 22:30:08.869568 update_engine[1368]: I0805 22:30:08.869365 1368 update_attempter.cc:509] Updating boot flags... Aug 5 22:30:08.916382 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1822) Aug 5 22:30:08.947766 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1823) Aug 5 22:30:08.984780 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1823) Aug 5 22:30:48.842389 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:30:48.841Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"107.382026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/cilium-ca\" ","response":"range_response_count:0 size:5"} Aug 5 22:30:48.842389 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:48.841Z","caller":"traceutil/trace.go:171","msg":"trace[1623680475] range","detail":"{range_begin:/registry/secrets/kube-system/cilium-ca; range_end:; response_count:0; response_revision:329; }","duration":"107.573115ms","start":"2024-08-05T22:30:48.734Z","end":"2024-08-05T22:30:48.841Z","steps":["trace[1623680475] 'agreement among raft nodes before linearized reading' (duration: 65.928111ms)","trace[1623680475] 'range keys from in-memory index tree' (duration: 41.433417ms)"],"step_count":2} Aug 5 22:30:58.115309 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:30:58.114Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"137.060461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:30:58.115309 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.114Z","caller":"traceutil/trace.go:171","msg":"trace[1737427879] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"137.708547ms","start":"2024-08-05T22:30:57.977Z","end":"2024-08-05T22:30:58.114Z","steps":["trace[1737427879] 'compare' (duration: 136.926961ms)"],"step_count":1} Aug 5 22:30:58.136434 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.135Z","caller":"traceutil/trace.go:171","msg":"trace[841291881] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"117.537702ms","start":"2024-08-05T22:30:58.018Z","end":"2024-08-05T22:30:58.135Z","steps":["trace[841291881] 'read index received' (duration: 117.30738ms)","trace[841291881] 'applied index is now lower than readState.Index' (duration: 229.671µs)"],"step_count":2} Aug 5 22:30:58.136434 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.135Z","caller":"traceutil/trace.go:171","msg":"trace[69134063] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"156.45053ms","start":"2024-08-05T22:30:57.979Z","end":"2024-08-05T22:30:58.135Z","steps":["trace[69134063] 'process raft request' (duration: 156.219678ms)"],"step_count":1} Aug 5 22:30:58.136434 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:30:58.135Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"117.728309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} Aug 5 22:30:58.136434 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.136Z","caller":"traceutil/trace.go:171","msg":"trace[44277856] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:389; }","duration":"117.779766ms","start":"2024-08-05T22:30:58.018Z","end":"2024-08-05T22:30:58.136Z","steps":["trace[44277856] 'agreement among raft nodes before linearized reading' (duration: 117.705446ms)"],"step_count":1} Aug 5 22:30:58.991096 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.990Z","caller":"traceutil/trace.go:171","msg":"trace[124601734] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"116.131984ms","start":"2024-08-05T22:30:58.874Z","end":"2024-08-05T22:30:58.990Z","steps":["trace[124601734] 'process raft request' (duration: 80.009502ms)","trace[124601734] 'compare' (duration: 35.825365ms)"],"step_count":2} Aug 5 22:30:58.991096 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.990Z","caller":"traceutil/trace.go:171","msg":"trace[1820393538] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"113.481852ms","start":"2024-08-05T22:30:58.877Z","end":"2024-08-05T22:30:58.990Z","steps":["trace[1820393538] 'process raft request' (duration: 113.354213ms)"],"step_count":1} Aug 5 22:30:58.991096 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.990Z","caller":"traceutil/trace.go:171","msg":"trace[293955164] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"111.645337ms","start":"2024-08-05T22:30:58.879Z","end":"2024-08-05T22:30:58.990Z","steps":["trace[293955164] 'process raft request' (duration: 111.52948ms)"],"step_count":1} Aug 5 22:30:58.991096 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.990Z","caller":"traceutil/trace.go:171","msg":"trace[1164700576] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"112.672223ms","start":"2024-08-05T22:30:58.877Z","end":"2024-08-05T22:30:58.990Z","steps":["trace[1164700576] 'process raft request' (duration: 112.57466ms)"],"step_count":1} Aug 5 22:30:58.991096 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:30:58.990Z","caller":"traceutil/trace.go:171","msg":"trace[567590819] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"109.185592ms","start":"2024-08-05T22:30:58.881Z","end":"2024-08-05T22:30:58.990Z","steps":["trace[567590819] 'process raft request' (duration: 108.973494ms)"],"step_count":1} Aug 5 22:31:11.508804 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:11.508Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"180.405677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:31:11.508804 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:11.508Z","caller":"traceutil/trace.go:171","msg":"trace[1206414675] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"180.714221ms","start":"2024-08-05T22:31:11.327Z","end":"2024-08-05T22:31:11.508Z","steps":["trace[1206414675] 'compare' (duration: 180.304985ms)"],"step_count":1} Aug 5 22:31:13.228204 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:13.227Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"120.60843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:31:13.228204 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:13.227Z","caller":"traceutil/trace.go:171","msg":"trace[269798864] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"164.755221ms","start":"2024-08-05T22:31:13.063Z","end":"2024-08-05T22:31:13.227Z","steps":["trace[269798864] 'process raft request' (duration: 43.976854ms)","trace[269798864] 'compare' (duration: 120.524249ms)"],"step_count":2} Aug 5 22:31:15.472539 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:15.472Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"187.871131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:15.472539 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:15.472Z","caller":"traceutil/trace.go:171","msg":"trace[333200232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"187.941145ms","start":"2024-08-05T22:31:15.284Z","end":"2024-08-05T22:31:15.472Z","steps":["trace[333200232] 'agreement among raft nodes before linearized reading' (duration: 60.971715ms)","trace[333200232] 'range keys from in-memory index tree' (duration: 126.888034ms)"],"step_count":2} Aug 5 22:31:15.709826 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:15.709Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"182.96604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/10.0.0.109\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:15.709826 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:15.709Z","caller":"traceutil/trace.go:171","msg":"trace[1254890115] range","detail":"{range_begin:/registry/csinodes/10.0.0.109; range_end:; response_count:0; response_revision:497; }","duration":"183.031136ms","start":"2024-08-05T22:31:15.526Z","end":"2024-08-05T22:31:15.709Z","steps":["trace[1254890115] 'agreement among raft nodes before linearized reading' (duration: 52.924734ms)","trace[1254890115] 'range keys from in-memory index tree' (duration: 130.031078ms)"],"step_count":2} Aug 5 22:31:15.709826 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:15.709Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"130.093597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:31:15.709826 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:15.709Z","caller":"traceutil/trace.go:171","msg":"trace[765697276] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"179.508846ms","start":"2024-08-05T22:31:15.530Z","end":"2024-08-05T22:31:15.709Z","steps":["trace[765697276] 'process raft request' (duration: 49.281863ms)","trace[765697276] 'compare' (duration: 129.99572ms)"],"step_count":2} Aug 5 22:31:15.710308 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:15.709Z","caller":"traceutil/trace.go:171","msg":"trace[978617636] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"130.423421ms","start":"2024-08-05T22:31:15.579Z","end":"2024-08-05T22:31:15.709Z","steps":["trace[978617636] 'read index received' (duration: 130.024756ms)","trace[978617636] 'applied index is now lower than readState.Index' (duration: 396.03µs)"],"step_count":2} Aug 5 22:31:15.710308 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:15.709Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"180.352002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/10.0.0.109\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:15.710308 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:15.709Z","caller":"traceutil/trace.go:171","msg":"trace[672936497] range","detail":"{range_begin:/registry/leases/kube-node-lease/10.0.0.109; range_end:; response_count:0; response_revision:500; }","duration":"180.380156ms","start":"2024-08-05T22:31:15.529Z","end":"2024-08-05T22:31:15.709Z","steps":["trace[672936497] 'agreement among raft nodes before linearized reading' (duration: 180.313789ms)"],"step_count":1} Aug 5 22:31:16.028743 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.028Z","caller":"traceutil/trace.go:171","msg":"trace[1718613448] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:515; }","duration":"318.273631ms","start":"2024-08-05T22:31:15.709Z","end":"2024-08-05T22:31:16.028Z","steps":["trace[1718613448] 'read index received' (duration: 318.263022ms)","trace[1718613448] 'applied index is now lower than readState.Index' (duration: 9.137µs)"],"step_count":2} Aug 5 22:31:16.028954 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.028Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"391.402114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.109\" ","response":"range_response_count:1 size:1964"} Aug 5 22:31:16.028954 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.028Z","caller":"traceutil/trace.go:171","msg":"trace[1662665742] range","detail":"{range_begin:/registry/minions/10.0.0.109; range_end:; response_count:1; response_revision:500; }","duration":"391.458993ms","start":"2024-08-05T22:31:15.636Z","end":"2024-08-05T22:31:16.028Z","steps":["trace[1662665742] 'agreement among raft nodes before linearized reading' (duration: 391.358761ms)"],"step_count":1} Aug 5 22:31:16.028954 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.636Z","time spent":"391.503128ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":1988,"request content":"key:\"/registry/minions/10.0.0.109\" "} Aug 5 22:31:16.028954 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.028Z","caller":"traceutil/trace.go:171","msg":"trace[1549961418] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"314.160298ms","start":"2024-08-05T22:31:15.714Z","end":"2024-08-05T22:31:16.028Z","steps":["trace[1549961418] 'process raft request' (duration: 314.095884ms)"],"step_count":1} Aug 5 22:31:16.028954 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.028Z","caller":"traceutil/trace.go:171","msg":"trace[615098953] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"312.599597ms","start":"2024-08-05T22:31:15.715Z","end":"2024-08-05T22:31:16.028Z","steps":["trace[615098953] 'process raft request' (duration: 312.468777ms)"],"step_count":1} Aug 5 22:31:16.029159 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.714Z","time spent":"314.262664ms","remote":"10.0.0.102:45806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":637,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.029159 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.028Z","caller":"traceutil/trace.go:171","msg":"trace[222042975] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"314.522152ms","start":"2024-08-05T22:31:15.713Z","end":"2024-08-05T22:31:16.028Z","steps":["trace[222042975] 'process raft request' (duration: 314.201408ms)"],"step_count":1} Aug 5 22:31:16.029159 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.715Z","time spent":"312.631869ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":32,"response count":0,"response size":2171,"request content":"compare: success:> failure: >"} Aug 5 22:31:16.029339 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.713Z","time spent":"314.59443ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Aug 5 22:31:16.169839 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.169Z","caller":"traceutil/trace.go:171","msg":"trace[592963778] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:518; }","duration":"141.148763ms","start":"2024-08-05T22:31:16.028Z","end":"2024-08-05T22:31:16.169Z","steps":["trace[592963778] 'read index received' (duration: 141.133113ms)","trace[592963778] 'applied index is now lower than readState.Index' (duration: 13.667µs)"],"step_count":2} Aug 5 22:31:16.180476 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.179Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"466.581432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.109\" ","response":"range_response_count:1 size:2136"} Aug 5 22:31:16.180476 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.180Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"464.159952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:16.180476 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[533632266] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:502; }","duration":"464.195711ms","start":"2024-08-05T22:31:15.715Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[533632266] 'agreement among raft nodes before linearized reading' (duration: 453.550492ms)"],"step_count":1} Aug 5 22:31:16.180476 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.180Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.715Z","time spent":"464.242911ms","remote":"10.0.0.102:45808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} Aug 5 22:31:16.180476 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[224226580] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"149.312961ms","start":"2024-08-05T22:31:16.030Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[224226580] 'process raft request' (duration: 138.597998ms)","trace[224226580] 'compare' (duration: 10.430768ms)"],"step_count":2} Aug 5 22:31:16.180786 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[1726115150] range","detail":"{range_begin:/registry/minions/10.0.0.109; range_end:; response_count:1; response_revision:502; }","duration":"466.732162ms","start":"2024-08-05T22:31:15.713Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[1726115150] 'agreement among raft nodes before linearized reading' (duration: 456.113584ms)"],"step_count":1} Aug 5 22:31:16.180786 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.180Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.713Z","time spent":"466.89364ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":2160,"request content":"key:\"/registry/minions/10.0.0.109\" "} Aug 5 22:31:16.180786 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.179Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"468.675917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.109\" ","response":"range_response_count:1 size:2136"} Aug 5 22:31:16.180786 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[385302346] range","detail":"{range_begin:/registry/minions/10.0.0.109; range_end:; response_count:1; response_revision:502; }","duration":"469.009024ms","start":"2024-08-05T22:31:15.711Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[385302346] 'agreement among raft nodes before linearized reading' (duration: 458.283332ms)"],"step_count":1} Aug 5 22:31:16.180786 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.180Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:15.711Z","time spent":"469.036447ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":2160,"request content":"key:\"/registry/minions/10.0.0.109\" "} Aug 5 22:31:16.180929 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[1759216552] transaction","detail":"{read_only:false; number_of_response:1; response_revision:504; }","duration":"147.711464ms","start":"2024-08-05T22:31:16.032Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[1759216552] 'process raft request' (duration: 147.689462ms)"],"step_count":1} Aug 5 22:31:16.180929 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.180Z","caller":"traceutil/trace.go:171","msg":"trace[872652308] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"149.074534ms","start":"2024-08-05T22:31:16.031Z","end":"2024-08-05T22:31:16.180Z","steps":["trace[872652308] 'process raft request' (duration: 148.852559ms)"],"step_count":1} Aug 5 22:31:16.553934 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.553Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"270.888143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:31:16.554698 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.553Z","caller":"traceutil/trace.go:171","msg":"trace[1833926478] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"376.227781ms","start":"2024-08-05T22:31:16.177Z","end":"2024-08-05T22:31:16.553Z","steps":["trace[1833926478] 'process raft request' (duration: 105.120568ms)","trace[1833926478] 'compare' (duration: 270.717116ms)"],"step_count":2} Aug 5 22:31:16.554698 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.553Z","caller":"traceutil/trace.go:171","msg":"trace[804452301] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"367.254313ms","start":"2024-08-05T22:31:16.186Z","end":"2024-08-05T22:31:16.553Z","steps":["trace[804452301] 'read index received' (duration: 96.008655ms)","trace[804452301] 'applied index is now lower than readState.Index' (duration: 271.245077ms)"],"step_count":2} Aug 5 22:31:16.554698 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.177Z","time spent":"376.309738ms","remote":"10.0.0.102:45806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.554698 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.553Z","caller":"traceutil/trace.go:171","msg":"trace[911941834] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"366.747654ms","start":"2024-08-05T22:31:16.187Z","end":"2024-08-05T22:31:16.553Z","steps":["trace[911941834] 'process raft request' (duration: 366.533774ms)"],"step_count":1} Aug 5 22:31:16.554926 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.187Z","time spent":"366.800795ms","remote":"10.0.0.102:45882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3357,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.554926 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.553Z","caller":"traceutil/trace.go:171","msg":"trace[1486398682] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"366.722335ms","start":"2024-08-05T22:31:16.187Z","end":"2024-08-05T22:31:16.553Z","steps":["trace[1486398682] 'process raft request' (duration: 366.529776ms)"],"step_count":1} Aug 5 22:31:16.554926 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.554Z","caller":"traceutil/trace.go:171","msg":"trace[506315085] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"371.59144ms","start":"2024-08-05T22:31:16.182Z","end":"2024-08-05T22:31:16.554Z","steps":["trace[506315085] 'process raft request' (duration: 371.223495ms)"],"step_count":1} Aug 5 22:31:16.554926 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.187Z","time spent":"366.774494ms","remote":"10.0.0.102:45882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13710,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.555068 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.182Z","time spent":"371.629444ms","remote":"10.0.0.102:45880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2443,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Aug 5 22:31:16.555068 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.554Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"367.569569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xpwhx\" ","response":"range_response_count:1 size:16302"} Aug 5 22:31:16.555068 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.554Z","caller":"traceutil/trace.go:171","msg":"trace[831785238] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xpwhx; range_end:; response_count:1; response_revision:508; }","duration":"367.586561ms","start":"2024-08-05T22:31:16.186Z","end":"2024-08-05T22:31:16.554Z","steps":["trace[831785238] 'agreement among raft nodes before linearized reading' (duration: 367.547035ms)"],"step_count":1} Aug 5 22:31:16.555068 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.186Z","time spent":"367.619774ms","remote":"10.0.0.102:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16326,"request content":"key:\"/registry/pods/kube-system/cilium-xpwhx\" "} Aug 5 22:31:16.568801 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.568Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"282.917172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:16.568801 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.568Z","caller":"traceutil/trace.go:171","msg":"trace[1805681973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"282.968671ms","start":"2024-08-05T22:31:16.285Z","end":"2024-08-05T22:31:16.568Z","steps":["trace[1805681973] 'agreement among raft nodes before linearized reading' (duration: 282.898155ms)"],"step_count":1} Aug 5 22:31:16.777946 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.777Z","caller":"traceutil/trace.go:171","msg":"trace[986801143] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:531; }","duration":"209.123224ms","start":"2024-08-05T22:31:16.568Z","end":"2024-08-05T22:31:16.777Z","steps":["trace[986801143] 'read index received' (duration: 209.113125ms)","trace[986801143] 'applied index is now lower than readState.Index' (duration: 8.596µs)"],"step_count":2} Aug 5 22:31:16.916679 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.916Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"358.929679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-xpwhx.17e8f5bf38c1c804\" ","response":"range_response_count:1 size:788"} Aug 5 22:31:16.916679 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.916Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"138.50029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:31:16.916679 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.916Z","caller":"traceutil/trace.go:171","msg":"trace[2040236576] range","detail":"{range_begin:/registry/events/kube-system/cilium-xpwhx.17e8f5bf38c1c804; range_end:; response_count:1; response_revision:514; }","duration":"359.043587ms","start":"2024-08-05T22:31:16.557Z","end":"2024-08-05T22:31:16.916Z","steps":["trace[2040236576] 'agreement among raft nodes before linearized reading' (duration: 220.412385ms)","trace[2040236576] 'range keys from in-memory index tree' (duration: 138.459241ms)"],"step_count":2} Aug 5 22:31:16.916679 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.557Z","time spent":"359.106988ms","remote":"10.0.0.102:45806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":812,"request content":"key:\"/registry/events/kube-system/cilium-xpwhx.17e8f5bf38c1c804\" "} Aug 5 22:31:16.917312 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.916Z","caller":"traceutil/trace.go:171","msg":"trace[886973363] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"283.465503ms","start":"2024-08-05T22:31:16.632Z","end":"2024-08-05T22:31:16.916Z","steps":["trace[886973363] 'process raft request' (duration: 283.428642ms)"],"step_count":1} Aug 5 22:31:16.917312 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.916Z","caller":"traceutil/trace.go:171","msg":"trace[1646089134] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"344.41004ms","start":"2024-08-05T22:31:16.572Z","end":"2024-08-05T22:31:16.916Z","steps":["trace[1646089134] 'process raft request' (duration: 205.62294ms)","trace[1646089134] 'compare' (duration: 138.366765ms)"],"step_count":2} Aug 5 22:31:16.917312 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.572Z","time spent":"344.442652ms","remote":"10.0.0.102:45806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.917312 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.916Z","caller":"traceutil/trace.go:171","msg":"trace[1723151253] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"343.289644ms","start":"2024-08-05T22:31:16.573Z","end":"2024-08-05T22:31:16.916Z","steps":["trace[1723151253] 'process raft request' (duration: 343.097917ms)"],"step_count":1} Aug 5 22:31:16.917476 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.573Z","time spent":"343.335962ms","remote":"10.0.0.102:45806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Aug 5 22:31:16.917476 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.916Z","caller":"traceutil/trace.go:171","msg":"trace[1194373199] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:534; }","duration":"139.329768ms","start":"2024-08-05T22:31:16.777Z","end":"2024-08-05T22:31:16.916Z","steps":["trace[1194373199] 'read index received' (duration: 139.322194ms)","trace[1194373199] 'applied index is now lower than readState.Index' (duration: 6.302µs)"],"step_count":2} Aug 5 22:31:16.917476 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"338.830314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:12890"} Aug 5 22:31:16.917476 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.917Z","caller":"traceutil/trace.go:171","msg":"trace[545114628] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:517; }","duration":"338.862745ms","start":"2024-08-05T22:31:16.578Z","end":"2024-08-05T22:31:16.917Z","steps":["trace[545114628] 'agreement among raft nodes before linearized reading' (duration: 338.786759ms)"],"step_count":1} Aug 5 22:31:16.917620 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.578Z","time spent":"338.885269ms","remote":"10.0.0.102:46172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":12914,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} Aug 5 22:31:16.917620 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"342.091068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2920"} Aug 5 22:31:16.917620 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"348.162357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:16.917620 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.917Z","caller":"traceutil/trace.go:171","msg":"trace[284716731] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:518; }","duration":"342.106798ms","start":"2024-08-05T22:31:16.575Z","end":"2024-08-05T22:31:16.917Z","steps":["trace[284716731] 'agreement among raft nodes before linearized reading' (duration: 342.075549ms)"],"step_count":1} Aug 5 22:31:16.917620 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:16.917Z","caller":"traceutil/trace.go:171","msg":"trace[898803699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"348.19031ms","start":"2024-08-05T22:31:16.569Z","end":"2024-08-05T22:31:16.917Z","steps":["trace[898803699] 'agreement among raft nodes before linearized reading' (duration: 348.152046ms)"],"step_count":1} Aug 5 22:31:16.917919 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.575Z","time spent":"342.131094ms","remote":"10.0.0.102:46172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2944,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} Aug 5 22:31:16.917919 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:16.917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:16.569Z","time spent":"348.218093ms","remote":"10.0.0.102:45742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Aug 5 22:31:17.549906 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:17.549Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"173.197025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:31:17.549906 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:17.549Z","caller":"traceutil/trace.go:171","msg":"trace[424283988] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"173.543368ms","start":"2024-08-05T22:31:17.376Z","end":"2024-08-05T22:31:17.549Z","steps":["trace[424283988] 'compare' (duration: 173.06919ms)"],"step_count":1} Aug 5 22:31:18.641342 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:18.640Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"152.795569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} Aug 5 22:31:18.641342 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.640Z","caller":"traceutil/trace.go:171","msg":"trace[454998014] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"152.89526ms","start":"2024-08-05T22:31:18.488Z","end":"2024-08-05T22:31:18.640Z","steps":["trace[454998014] 'agreement among raft nodes before linearized reading' (duration: 109.257508ms)","trace[454998014] 'range keys from in-memory index tree' (duration: 43.519536ms)"],"step_count":2} Aug 5 22:31:18.641342 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.640Z","caller":"traceutil/trace.go:171","msg":"trace[918177867] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"116.641948ms","start":"2024-08-05T22:31:18.524Z","end":"2024-08-05T22:31:18.640Z","steps":["trace[918177867] 'process raft request' (duration: 73.098476ms)","trace[918177867] 'compare' (duration: 43.414385ms)"],"step_count":2} Aug 5 22:31:18.641342 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.641Z","caller":"traceutil/trace.go:171","msg":"trace[914613592] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.659638ms","start":"2024-08-05T22:31:18.528Z","end":"2024-08-05T22:31:18.641Z","steps":["trace[914613592] 'process raft request' (duration: 112.539088ms)"],"step_count":1} Aug 5 22:31:18.976945 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:18.976Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"232.273621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:31:18.977361 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.976Z","caller":"traceutil/trace.go:171","msg":"trace[1183447213] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"305.851837ms","start":"2024-08-05T22:31:18.670Z","end":"2024-08-05T22:31:18.976Z","steps":["trace[1183447213] 'process raft request' (duration: 73.344128ms)","trace[1183447213] 'compare' (duration: 232.179291ms)"],"step_count":2} Aug 5 22:31:18.977361 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:18.976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:18.670Z","time spent":"305.985663ms","remote":"10.0.0.102:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21747,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Aug 5 22:31:18.977361 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.976Z","caller":"traceutil/trace.go:171","msg":"trace[775395572] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"268.872385ms","start":"2024-08-05T22:31:18.707Z","end":"2024-08-05T22:31:18.976Z","steps":["trace[775395572] 'process raft request' (duration: 268.756183ms)"],"step_count":1} Aug 5 22:31:18.977361 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.976Z","caller":"traceutil/trace.go:171","msg":"trace[2050894322] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"301.836815ms","start":"2024-08-05T22:31:18.675Z","end":"2024-08-05T22:31:18.976Z","steps":["trace[2050894322] 'process raft request' (duration: 301.569814ms)"],"step_count":1} Aug 5 22:31:18.977496 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:18.976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2024-08-05T22:31:18.675Z","time spent":"301.87653ms","remote":"10.0.0.102:45776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13202,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Aug 5 22:31:18.996460 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:18.996Z","caller":"traceutil/trace.go:171","msg":"trace[118093818] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"102.9631ms","start":"2024-08-05T22:31:18.893Z","end":"2024-08-05T22:31:18.996Z","steps":["trace[118093818] 'process raft request' (duration: 102.586018ms)"],"step_count":1} Aug 5 22:31:19.313142 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:19.312Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"247.641934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} Aug 5 22:31:19.313142 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.312Z","caller":"traceutil/trace.go:171","msg":"trace[1571136209] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:562; }","duration":"247.734121ms","start":"2024-08-05T22:31:19.064Z","end":"2024-08-05T22:31:19.312Z","steps":["trace[1571136209] 'agreement among raft nodes before linearized reading' (duration: 81.931537ms)","trace[1571136209] 'range keys from in-memory index tree' (duration: 165.671883ms)"],"step_count":2} Aug 5 22:31:19.313419 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:19.312Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"165.872157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:31:19.313419 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.312Z","caller":"traceutil/trace.go:171","msg":"trace[1812713051] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"228.627524ms","start":"2024-08-05T22:31:19.084Z","end":"2024-08-05T22:31:19.312Z","steps":["trace[1812713051] 'process raft request' (duration: 62.607785ms)","trace[1812713051] 'compare' (duration: 165.783257ms)"],"step_count":2} Aug 5 22:31:19.345741 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:19.345Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"124.041885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-599987898-2clm7.17e8f5bf44eadc38\" ","response":"range_response_count:1 size:845"} Aug 5 22:31:19.345741 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.345Z","caller":"traceutil/trace.go:171","msg":"trace[1202730102] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-599987898-2clm7.17e8f5bf44eadc38; range_end:; response_count:1; response_revision:564; }","duration":"124.128931ms","start":"2024-08-05T22:31:19.221Z","end":"2024-08-05T22:31:19.345Z","steps":["trace[1202730102] 'agreement among raft nodes before linearized reading' (duration: 123.98669ms)"],"step_count":1} Aug 5 22:31:19.604581 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.603Z","caller":"traceutil/trace.go:171","msg":"trace[275439434] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"235.633772ms","start":"2024-08-05T22:31:19.368Z","end":"2024-08-05T22:31:19.603Z","steps":["trace[275439434] 'process raft request' (duration: 143.062458ms)","trace[275439434] 'compare' (duration: 92.395248ms)"],"step_count":2} Aug 5 22:31:19.604581 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.604Z","caller":"traceutil/trace.go:171","msg":"trace[1267544057] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"233.261507ms","start":"2024-08-05T22:31:19.370Z","end":"2024-08-05T22:31:19.604Z","steps":["trace[1267544057] 'process raft request' (duration: 232.972354ms)"],"step_count":1} Aug 5 22:31:19.604581 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.604Z","caller":"traceutil/trace.go:171","msg":"trace[1849449390] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"229.295353ms","start":"2024-08-05T22:31:19.375Z","end":"2024-08-05T22:31:19.604Z","steps":["trace[1849449390] 'process raft request' (duration: 228.902781ms)"],"step_count":1} Aug 5 22:31:19.604906 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.604Z","caller":"traceutil/trace.go:171","msg":"trace[1646067744] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"234.506097ms","start":"2024-08-05T22:31:19.370Z","end":"2024-08-05T22:31:19.604Z","steps":["trace[1646067744] 'read index received' (duration: 141.030693ms)","trace[1646067744] 'applied index is now lower than readState.Index' (duration: 93.474763ms)"],"step_count":2} Aug 5 22:31:19.605067 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:19.604Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"234.739263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-xpwhx.17e8f5bf38c1c804\" ","response":"range_response_count:1 size:788"} Aug 5 22:31:19.605161 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.604Z","caller":"traceutil/trace.go:171","msg":"trace[1407683792] range","detail":"{range_begin:/registry/events/kube-system/cilium-xpwhx.17e8f5bf38c1c804; range_end:; response_count:1; response_revision:571; }","duration":"234.901563ms","start":"2024-08-05T22:31:19.370Z","end":"2024-08-05T22:31:19.604Z","steps":["trace[1407683792] 'agreement among raft nodes before linearized reading' (duration: 234.714235ms)"],"step_count":1} Aug 5 22:31:19.605161 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.604Z","caller":"traceutil/trace.go:171","msg":"trace[1905155694] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"226.46551ms","start":"2024-08-05T22:31:19.378Z","end":"2024-08-05T22:31:19.604Z","steps":["trace[1905155694] 'process raft request' (duration: 225.751084ms)"],"step_count":1} Aug 5 22:31:19.632483 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:31:19.632Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"176.201351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:21762"} Aug 5 22:31:19.632483 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.632Z","caller":"traceutil/trace.go:171","msg":"trace[982167717] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"176.274609ms","start":"2024-08-05T22:31:19.455Z","end":"2024-08-05T22:31:19.632Z","steps":["trace[982167717] 'agreement among raft nodes before linearized reading' (duration: 176.145273ms)"],"step_count":1} Aug 5 22:31:19.916740 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.916Z","caller":"traceutil/trace.go:171","msg":"trace[1011297224] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"131.777036ms","start":"2024-08-05T22:31:19.784Z","end":"2024-08-05T22:31:19.916Z","steps":["trace[1011297224] 'process raft request' (duration: 131.744474ms)"],"step_count":1} Aug 5 22:31:19.916740 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:31:19.916Z","caller":"traceutil/trace.go:171","msg":"trace[1549139339] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"180.780707ms","start":"2024-08-05T22:31:19.735Z","end":"2024-08-05T22:31:19.916Z","steps":["trace[1549139339] 'process raft request' (duration: 92.368214ms)","trace[1549139339] 'compare' (duration: 88.192332ms)"],"step_count":2} Aug 5 22:32:18.261574 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.260Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"155.909698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:32:18.261574 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.261Z","caller":"traceutil/trace.go:171","msg":"trace[436573824] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"193.422068ms","start":"2024-08-05T22:32:18.067Z","end":"2024-08-05T22:32:18.261Z","steps":["trace[436573824] 'read index received' (duration: 37.314416ms)","trace[436573824] 'applied index is now lower than readState.Index' (duration: 156.10699ms)"],"step_count":2} Aug 5 22:32:18.261574 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.261Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"114.976244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-dynamic-volume-claim\" ","response":"range_response_count:1 size:1720"} Aug 5 22:32:18.261574 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.261Z","caller":"traceutil/trace.go:171","msg":"trace[978785038] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"194.191429ms","start":"2024-08-05T22:32:18.066Z","end":"2024-08-05T22:32:18.261Z","steps":["trace[978785038] 'process raft request' (duration: 38.106611ms)","trace[978785038] 'compare' (duration: 155.809058ms)"],"step_count":2} Aug 5 22:32:18.261574 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.261Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"193.584876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pod-1\" ","response":"range_response_count:1 size:2177"} Aug 5 22:32:18.262494 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.261Z","caller":"traceutil/trace.go:171","msg":"trace[1045869185] range","detail":"{range_begin:/registry/pods/default/test-pod-1; range_end:; response_count:1; response_revision:878; }","duration":"193.596948ms","start":"2024-08-05T22:32:18.067Z","end":"2024-08-05T22:32:18.261Z","steps":["trace[1045869185] 'agreement among raft nodes before linearized reading' (duration: 193.4872ms)"],"step_count":1} Aug 5 22:32:18.262494 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.261Z","caller":"traceutil/trace.go:171","msg":"trace[2120059217] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-dynamic-volume-claim; range_end:; response_count:1; response_revision:878; }","duration":"115.00576ms","start":"2024-08-05T22:32:18.146Z","end":"2024-08-05T22:32:18.261Z","steps":["trace[2120059217] 'agreement among raft nodes before linearized reading' (duration: 114.936089ms)"],"step_count":1} Aug 5 22:32:18.470204 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.469Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"184.841327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Aug 5 22:32:18.470204 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.469Z","caller":"traceutil/trace.go:171","msg":"trace[1481269975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"184.924324ms","start":"2024-08-05T22:32:18.284Z","end":"2024-08-05T22:32:18.469Z","steps":["trace[1481269975] 'agreement among raft nodes before linearized reading' (duration: 76.999978ms)","trace[1481269975] 'range keys from in-memory index tree' (duration: 107.82536ms)"],"step_count":2} Aug 5 22:32:18.470204 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.469Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"107.857661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:32:18.470204 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.469Z","caller":"traceutil/trace.go:171","msg":"trace[1017907457] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"108.072997ms","start":"2024-08-05T22:32:18.361Z","end":"2024-08-05T22:32:18.469Z","steps":["trace[1017907457] 'read index received' (duration: 20.287069ms)","trace[1017907457] 'applied index is now lower than readState.Index' (duration: 87.785137ms)"],"step_count":2} Aug 5 22:32:18.470606 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:18.469Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"154.540084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-dynamic-volume-claim\" ","response":"range_response_count:1 size:1720"} Aug 5 22:32:18.470606 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.469Z","caller":"traceutil/trace.go:171","msg":"trace[355927819] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-dynamic-volume-claim; range_end:; response_count:1; response_revision:880; }","duration":"154.631285ms","start":"2024-08-05T22:32:18.315Z","end":"2024-08-05T22:32:18.469Z","steps":["trace[355927819] 'agreement among raft nodes before linearized reading' (duration: 154.503013ms)"],"step_count":1} Aug 5 22:32:18.470606 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:18.469Z","caller":"traceutil/trace.go:171","msg":"trace[1023238008] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"137.040264ms","start":"2024-08-05T22:32:18.332Z","end":"2024-08-05T22:32:18.469Z","steps":["trace[1023238008] 'process raft request' (duration: 29.027941ms)","trace[1023238008] 'compare' (duration: 107.768132ms)"],"step_count":2} Aug 5 22:32:28.432350 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:28.431Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"128.67576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Aug 5 22:32:28.432350 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:28.431Z","caller":"traceutil/trace.go:171","msg":"trace[201129550] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"192.102237ms","start":"2024-08-05T22:32:28.239Z","end":"2024-08-05T22:32:28.431Z","steps":["trace[201129550] 'process raft request' (duration: 63.234475ms)","trace[201129550] 'compare' (duration: 128.554151ms)"],"step_count":2} Aug 5 22:32:28.434469 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:28.434Z","caller":"traceutil/trace.go:171","msg":"trace[1633456656] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"109.310474ms","start":"2024-08-05T22:32:28.324Z","end":"2024-08-05T22:32:28.434Z","steps":["trace[1633456656] 'process raft request' (duration: 109.119834ms)"],"step_count":1} Aug 5 22:32:28.434469 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:28.434Z","caller":"traceutil/trace.go:171","msg":"trace[847395440] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"107.431561ms","start":"2024-08-05T22:32:28.326Z","end":"2024-08-05T22:32:28.434Z","steps":["trace[847395440] 'process raft request' (duration: 107.225553ms)"],"step_count":1} Aug 5 22:32:30.665704 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:30.665Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"103.512842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} Aug 5 22:32:30.665704 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:30.665Z","caller":"traceutil/trace.go:171","msg":"trace[949929663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"103.630494ms","start":"2024-08-05T22:32:30.561Z","end":"2024-08-05T22:32:30.665Z","steps":["trace[949929663] 'range keys from in-memory index tree' (duration: 103.351369ms)"],"step_count":1} Aug 5 22:32:31.421541 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:31.420Z","caller":"traceutil/trace.go:171","msg":"trace[603514142] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"135.645279ms","start":"2024-08-05T22:32:31.285Z","end":"2024-08-05T22:32:31.420Z","steps":["trace[603514142] 'read index received' (duration: 39.987336ms)","trace[603514142] 'applied index is now lower than readState.Index' (duration: 95.656781ms)"],"step_count":2} Aug 5 22:32:31.421541 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:31.421Z","caller":"traceutil/trace.go:171","msg":"trace[321404938] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"144.525462ms","start":"2024-08-05T22:32:31.276Z","end":"2024-08-05T22:32:31.420Z","steps":["trace[321404938] 'process raft request' (duration: 48.845919ms)","trace[321404938] 'compare' (duration: 95.503261ms)"],"step_count":2} Aug 5 22:32:31.421541 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:31.421Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"135.811504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Aug 5 22:32:31.421541 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:31.421Z","caller":"traceutil/trace.go:171","msg":"trace[104591166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"135.858692ms","start":"2024-08-05T22:32:31.285Z","end":"2024-08-05T22:32:31.421Z","steps":["trace[104591166] 'agreement among raft nodes before linearized reading' (duration: 135.774664ms)"],"step_count":1} Aug 5 22:32:34.086675 etcd-wrapper[1600]: {"level":"warn","ts":"2024-08-05T22:32:34.086Z","caller":"etcdserver/util.go:166","msg":"apply request took too long","took":"112.634221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Aug 5 22:32:34.086675 etcd-wrapper[1600]: {"level":"info","ts":"2024-08-05T22:32:34.086Z","caller":"traceutil/trace.go:171","msg":"trace[1837630859] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"125.143201ms","start":"2024-08-05T22:32:33.961Z","end":"2024-08-05T22:32:34.086Z","steps":["trace[1837630859] 'process raft request' (duration: 12.3603ms)","trace[1837630859] 'compare' (duration: 112.507742ms)"],"step_count":2}