Nov 12 22:44:39.008883 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Tue Nov 12 21:10:03 -00 2024
Nov 12 22:44:39.008907 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=714367a70d0d672ed3d7ccc2de5247f52d37046778a42409fc8a40b0511373b1
Nov 12 22:44:39.008920 kernel: BIOS-provided physical RAM map:
Nov 12 22:44:39.008928 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Nov 12 22:44:39.008935 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Nov 12 22:44:39.008943 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Nov 12 22:44:39.008952 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable
Nov 12 22:44:39.008960 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved
Nov 12 22:44:39.008968 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Nov 12 22:44:39.008977 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Nov 12 22:44:39.008985 kernel: NX (Execute Disable) protection: active
Nov 12 22:44:39.008993 kernel: APIC: Static calls initialized
Nov 12 22:44:39.009000 kernel: SMBIOS 2.8 present.
Nov 12 22:44:39.009009 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014
Nov 12 22:44:39.009018 kernel: Hypervisor detected: KVM
Nov 12 22:44:39.009028 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Nov 12 22:44:39.009036 kernel: kvm-clock: using sched offset of 7509778205 cycles
Nov 12 22:44:39.009045 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Nov 12 22:44:39.009054 kernel: tsc: Detected 1996.249 MHz processor
Nov 12 22:44:39.009063 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Nov 12 22:44:39.009072 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Nov 12 22:44:39.009080 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000
Nov 12 22:44:39.009088 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
Nov 12 22:44:39.009097 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Nov 12 22:44:39.009107 kernel: ACPI: Early table checksum verification disabled
Nov 12 22:44:39.009116 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS )
Nov 12 22:44:39.009124 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Nov 12 22:44:39.009133 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Nov 12 22:44:39.009141 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Nov 12 22:44:39.009149 kernel: ACPI: FACS 0x000000007FFE0000 000040
Nov 12 22:44:39.009159 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Nov 12 22:44:39.009167 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Nov 12 22:44:39.009175 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f]
Nov 12 22:44:39.009185 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b]
Nov 12 22:44:39.009272 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
Nov 12 22:44:39.009281 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f]
Nov 12 22:44:39.009289 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847]
Nov 12 22:44:39.009296 kernel: No NUMA configuration found
Nov 12 22:44:39.009304 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff]
Nov 12 22:44:39.009312 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff]
Nov 12 22:44:39.009324 kernel: Zone ranges:
Nov 12 22:44:39.009334 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Nov 12 22:44:39.009342 kernel:   DMA32    [mem 0x0000000001000000-0x000000007ffdcfff]
Nov 12 22:44:39.009350 kernel:   Normal   empty
Nov 12 22:44:39.009358 kernel: Movable zone start for each node
Nov 12 22:44:39.009367 kernel: Early memory node ranges
Nov 12 22:44:39.009375 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Nov 12 22:44:39.009384 kernel:   node   0: [mem 0x0000000000100000-0x000000007ffdcfff]
Nov 12 22:44:39.009392 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff]
Nov 12 22:44:39.009401 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Nov 12 22:44:39.009409 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Nov 12 22:44:39.009417 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges
Nov 12 22:44:39.009425 kernel: ACPI: PM-Timer IO Port: 0x608
Nov 12 22:44:39.009433 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Nov 12 22:44:39.009441 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Nov 12 22:44:39.009449 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Nov 12 22:44:39.009459 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Nov 12 22:44:39.009467 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Nov 12 22:44:39.009475 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Nov 12 22:44:39.009483 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Nov 12 22:44:39.009492 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Nov 12 22:44:39.009502 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs
Nov 12 22:44:39.009511 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
Nov 12 22:44:39.009520 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices
Nov 12 22:44:39.009528 kernel: Booting paravirtualized kernel on KVM
Nov 12 22:44:39.009537 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Nov 12 22:44:39.009548 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
Nov 12 22:44:39.009557 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576
Nov 12 22:44:39.009566 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152
Nov 12 22:44:39.009574 kernel: pcpu-alloc: [0] 0 1 
Nov 12 22:44:39.009583 kernel: kvm-guest: PV spinlocks disabled, no host support
Nov 12 22:44:39.009594 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=714367a70d0d672ed3d7ccc2de5247f52d37046778a42409fc8a40b0511373b1
Nov 12 22:44:39.009603 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Nov 12 22:44:39.009614 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Nov 12 22:44:39.009623 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Nov 12 22:44:39.009631 kernel: Fallback order for Node 0: 0 
Nov 12 22:44:39.009640 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 515805
Nov 12 22:44:39.009649 kernel: Policy zone: DMA32
Nov 12 22:44:39.009657 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Nov 12 22:44:39.009666 kernel: Memory: 1971212K/2096620K available (12288K kernel code, 2305K rwdata, 22736K rodata, 42968K init, 2220K bss, 125148K reserved, 0K cma-reserved)
Nov 12 22:44:39.009675 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Nov 12 22:44:39.009684 kernel: ftrace: allocating 37801 entries in 148 pages
Nov 12 22:44:39.009695 kernel: ftrace: allocated 148 pages with 3 groups
Nov 12 22:44:39.009703 kernel: Dynamic Preempt: voluntary
Nov 12 22:44:39.009712 kernel: rcu: Preemptible hierarchical RCU implementation.
Nov 12 22:44:39.009721 kernel: rcu:         RCU event tracing is enabled.
Nov 12 22:44:39.009730 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Nov 12 22:44:39.009739 kernel:         Trampoline variant of Tasks RCU enabled.
Nov 12 22:44:39.009761 kernel:         Rude variant of Tasks RCU enabled.
Nov 12 22:44:39.009770 kernel:         Tracing variant of Tasks RCU enabled.
Nov 12 22:44:39.009779 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Nov 12 22:44:39.009788 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Nov 12 22:44:39.009799 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
Nov 12 22:44:39.009808 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Nov 12 22:44:39.009816 kernel: Console: colour VGA+ 80x25
Nov 12 22:44:39.009825 kernel: printk: console [tty0] enabled
Nov 12 22:44:39.009833 kernel: printk: console [ttyS0] enabled
Nov 12 22:44:39.009843 kernel: ACPI: Core revision 20230628
Nov 12 22:44:39.009851 kernel: APIC: Switch to symmetric I/O mode setup
Nov 12 22:44:39.009860 kernel: x2apic enabled
Nov 12 22:44:39.009869 kernel: APIC: Switched APIC routing to: physical x2apic
Nov 12 22:44:39.009879 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Nov 12 22:44:39.009889 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized
Nov 12 22:44:39.009897 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249)
Nov 12 22:44:39.009906 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Nov 12 22:44:39.009915 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Nov 12 22:44:39.009924 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Nov 12 22:44:39.009932 kernel: Spectre V2 : Mitigation: Retpolines
Nov 12 22:44:39.009942 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Nov 12 22:44:39.009951 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Nov 12 22:44:39.009961 kernel: Speculative Store Bypass: Vulnerable
Nov 12 22:44:39.009970 kernel: x86/fpu: x87 FPU will use FXSAVE
Nov 12 22:44:39.009978 kernel: Freeing SMP alternatives memory: 32K
Nov 12 22:44:39.009987 kernel: pid_max: default: 32768 minimum: 301
Nov 12 22:44:39.009997 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity
Nov 12 22:44:39.010005 kernel: landlock: Up and running.
Nov 12 22:44:39.010014 kernel: SELinux:  Initializing.
Nov 12 22:44:39.010023 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Nov 12 22:44:39.010040 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Nov 12 22:44:39.010050 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3)
Nov 12 22:44:39.010059 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Nov 12 22:44:39.010070 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Nov 12 22:44:39.010080 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Nov 12 22:44:39.010090 kernel: Performance Events: AMD PMU driver.
Nov 12 22:44:39.010099 kernel: ... version:                0
Nov 12 22:44:39.010108 kernel: ... bit width:              48
Nov 12 22:44:39.010119 kernel: ... generic registers:      4
Nov 12 22:44:39.010128 kernel: ... value mask:             0000ffffffffffff
Nov 12 22:44:39.010138 kernel: ... max period:             00007fffffffffff
Nov 12 22:44:39.010147 kernel: ... fixed-purpose events:   0
Nov 12 22:44:39.010156 kernel: ... event mask:             000000000000000f
Nov 12 22:44:39.010165 kernel: signal: max sigframe size: 1440
Nov 12 22:44:39.010174 kernel: rcu: Hierarchical SRCU implementation.
Nov 12 22:44:39.010183 kernel: rcu:         Max phase no-delay instances is 400.
Nov 12 22:44:39.010206 kernel: smp: Bringing up secondary CPUs ...
Nov 12 22:44:39.010218 kernel: smpboot: x86: Booting SMP configuration:
Nov 12 22:44:39.010227 kernel: .... node  #0, CPUs:      #1
Nov 12 22:44:39.010236 kernel: smp: Brought up 1 node, 2 CPUs
Nov 12 22:44:39.010245 kernel: smpboot: Max logical packages: 2
Nov 12 22:44:39.010255 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS)
Nov 12 22:44:39.010264 kernel: devtmpfs: initialized
Nov 12 22:44:39.010273 kernel: x86/mm: Memory block size: 128MB
Nov 12 22:44:39.010282 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Nov 12 22:44:39.010292 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Nov 12 22:44:39.010301 kernel: pinctrl core: initialized pinctrl subsystem
Nov 12 22:44:39.010312 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Nov 12 22:44:39.010321 kernel: audit: initializing netlink subsys (disabled)
Nov 12 22:44:39.010331 kernel: audit: type=2000 audit(1731451478.529:1): state=initialized audit_enabled=0 res=1
Nov 12 22:44:39.010340 kernel: thermal_sys: Registered thermal governor 'step_wise'
Nov 12 22:44:39.010349 kernel: thermal_sys: Registered thermal governor 'user_space'
Nov 12 22:44:39.010358 kernel: cpuidle: using governor menu
Nov 12 22:44:39.010368 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Nov 12 22:44:39.010377 kernel: dca service started, version 1.12.1
Nov 12 22:44:39.010387 kernel: PCI: Using configuration type 1 for base access
Nov 12 22:44:39.010398 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Nov 12 22:44:39.010407 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Nov 12 22:44:39.010416 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Nov 12 22:44:39.010426 kernel: ACPI: Added _OSI(Module Device)
Nov 12 22:44:39.010435 kernel: ACPI: Added _OSI(Processor Device)
Nov 12 22:44:39.010445 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Nov 12 22:44:39.010454 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Nov 12 22:44:39.010463 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Nov 12 22:44:39.010472 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC
Nov 12 22:44:39.010483 kernel: ACPI: Interpreter enabled
Nov 12 22:44:39.010492 kernel: ACPI: PM: (supports S0 S3 S5)
Nov 12 22:44:39.010501 kernel: ACPI: Using IOAPIC for interrupt routing
Nov 12 22:44:39.010511 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Nov 12 22:44:39.010520 kernel: PCI: Using E820 reservations for host bridge windows
Nov 12 22:44:39.010529 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F
Nov 12 22:44:39.010539 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Nov 12 22:44:39.010698 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Nov 12 22:44:39.010809 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
Nov 12 22:44:39.010907 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
Nov 12 22:44:39.010922 kernel: acpiphp: Slot [3] registered
Nov 12 22:44:39.010931 kernel: acpiphp: Slot [4] registered
Nov 12 22:44:39.010941 kernel: acpiphp: Slot [5] registered
Nov 12 22:44:39.010950 kernel: acpiphp: Slot [6] registered
Nov 12 22:44:39.010959 kernel: acpiphp: Slot [7] registered
Nov 12 22:44:39.010968 kernel: acpiphp: Slot [8] registered
Nov 12 22:44:39.010981 kernel: acpiphp: Slot [9] registered
Nov 12 22:44:39.010991 kernel: acpiphp: Slot [10] registered
Nov 12 22:44:39.011000 kernel: acpiphp: Slot [11] registered
Nov 12 22:44:39.011009 kernel: acpiphp: Slot [12] registered
Nov 12 22:44:39.011018 kernel: acpiphp: Slot [13] registered
Nov 12 22:44:39.011027 kernel: acpiphp: Slot [14] registered
Nov 12 22:44:39.011036 kernel: acpiphp: Slot [15] registered
Nov 12 22:44:39.011045 kernel: acpiphp: Slot [16] registered
Nov 12 22:44:39.011055 kernel: acpiphp: Slot [17] registered
Nov 12 22:44:39.011066 kernel: acpiphp: Slot [18] registered
Nov 12 22:44:39.011075 kernel: acpiphp: Slot [19] registered
Nov 12 22:44:39.011084 kernel: acpiphp: Slot [20] registered
Nov 12 22:44:39.011093 kernel: acpiphp: Slot [21] registered
Nov 12 22:44:39.011102 kernel: acpiphp: Slot [22] registered
Nov 12 22:44:39.011112 kernel: acpiphp: Slot [23] registered
Nov 12 22:44:39.011121 kernel: acpiphp: Slot [24] registered
Nov 12 22:44:39.011130 kernel: acpiphp: Slot [25] registered
Nov 12 22:44:39.011139 kernel: acpiphp: Slot [26] registered
Nov 12 22:44:39.011148 kernel: acpiphp: Slot [27] registered
Nov 12 22:44:39.011160 kernel: acpiphp: Slot [28] registered
Nov 12 22:44:39.011169 kernel: acpiphp: Slot [29] registered
Nov 12 22:44:39.011178 kernel: acpiphp: Slot [30] registered
Nov 12 22:44:39.011187 kernel: acpiphp: Slot [31] registered
Nov 12 22:44:39.011216 kernel: PCI host bridge to bus 0000:00
Nov 12 22:44:39.011320 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Nov 12 22:44:39.011412 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Nov 12 22:44:39.011499 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Nov 12 22:44:39.011592 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
Nov 12 22:44:39.011678 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
Nov 12 22:44:39.011766 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Nov 12 22:44:39.011888 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Nov 12 22:44:39.012006 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Nov 12 22:44:39.012112 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Nov 12 22:44:39.012246 kernel: pci 0000:00:01.1: reg 0x20: [io  0xc120-0xc12f]
Nov 12 22:44:39.012348 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
Nov 12 22:44:39.012447 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
Nov 12 22:44:39.012545 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
Nov 12 22:44:39.012643 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
Nov 12 22:44:39.012753 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Nov 12 22:44:39.012852 kernel: pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
Nov 12 22:44:39.012955 kernel: pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
Nov 12 22:44:39.013072 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000
Nov 12 22:44:39.013174 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref]
Nov 12 22:44:39.015351 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref]
Nov 12 22:44:39.015460 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff]
Nov 12 22:44:39.015560 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref]
Nov 12 22:44:39.015658 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Nov 12 22:44:39.015772 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
Nov 12 22:44:39.015871 kernel: pci 0000:00:03.0: reg 0x10: [io  0xc080-0xc0bf]
Nov 12 22:44:39.015968 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff]
Nov 12 22:44:39.016067 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref]
Nov 12 22:44:39.016164 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref]
Nov 12 22:44:39.016360 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
Nov 12 22:44:39.016467 kernel: pci 0000:00:04.0: reg 0x10: [io  0xc000-0xc07f]
Nov 12 22:44:39.016565 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff]
Nov 12 22:44:39.016661 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref]
Nov 12 22:44:39.016766 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00
Nov 12 22:44:39.016864 kernel: pci 0000:00:05.0: reg 0x10: [io  0xc0c0-0xc0ff]
Nov 12 22:44:39.016961 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref]
Nov 12 22:44:39.017065 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00
Nov 12 22:44:39.017170 kernel: pci 0000:00:06.0: reg 0x10: [io  0xc100-0xc11f]
Nov 12 22:44:39.017291 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref]
Nov 12 22:44:39.017307 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Nov 12 22:44:39.017317 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Nov 12 22:44:39.017326 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Nov 12 22:44:39.017336 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Nov 12 22:44:39.017345 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Nov 12 22:44:39.017355 kernel: iommu: Default domain type: Translated
Nov 12 22:44:39.017364 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Nov 12 22:44:39.017378 kernel: PCI: Using ACPI for IRQ routing
Nov 12 22:44:39.017388 kernel: PCI: pci_cache_line_size set to 64 bytes
Nov 12 22:44:39.017398 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Nov 12 22:44:39.017407 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff]
Nov 12 22:44:39.017503 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device
Nov 12 22:44:39.017601 kernel: pci 0000:00:02.0: vgaarb: bridge control possible
Nov 12 22:44:39.017698 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Nov 12 22:44:39.017712 kernel: vgaarb: loaded
Nov 12 22:44:39.017722 kernel: clocksource: Switched to clocksource kvm-clock
Nov 12 22:44:39.017736 kernel: VFS: Disk quotas dquot_6.6.0
Nov 12 22:44:39.017758 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Nov 12 22:44:39.017768 kernel: pnp: PnP ACPI init
Nov 12 22:44:39.017878 kernel: pnp 00:03: [dma 2]
Nov 12 22:44:39.017894 kernel: pnp: PnP ACPI: found 5 devices
Nov 12 22:44:39.017903 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Nov 12 22:44:39.017913 kernel: NET: Registered PF_INET protocol family
Nov 12 22:44:39.017923 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
Nov 12 22:44:39.017936 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
Nov 12 22:44:39.017946 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Nov 12 22:44:39.017956 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
Nov 12 22:44:39.017965 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
Nov 12 22:44:39.017975 kernel: TCP: Hash tables configured (established 16384 bind 16384)
Nov 12 22:44:39.017985 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
Nov 12 22:44:39.017994 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
Nov 12 22:44:39.018003 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Nov 12 22:44:39.018013 kernel: NET: Registered PF_XDP protocol family
Nov 12 22:44:39.018105 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Nov 12 22:44:39.018213 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Nov 12 22:44:39.018306 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Nov 12 22:44:39.018396 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
Nov 12 22:44:39.018481 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
Nov 12 22:44:39.018581 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release
Nov 12 22:44:39.018682 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Nov 12 22:44:39.018697 kernel: PCI: CLS 0 bytes, default 64
Nov 12 22:44:39.018710 kernel: Initialise system trusted keyrings
Nov 12 22:44:39.018720 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0
Nov 12 22:44:39.018729 kernel: Key type asymmetric registered
Nov 12 22:44:39.018739 kernel: Asymmetric key parser 'x509' registered
Nov 12 22:44:39.018749 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Nov 12 22:44:39.018758 kernel: io scheduler mq-deadline registered
Nov 12 22:44:39.018768 kernel: io scheduler kyber registered
Nov 12 22:44:39.018777 kernel: io scheduler bfq registered
Nov 12 22:44:39.018787 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Nov 12 22:44:39.018799 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10
Nov 12 22:44:39.018809 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11
Nov 12 22:44:39.018818 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11
Nov 12 22:44:39.018827 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10
Nov 12 22:44:39.018838 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Nov 12 22:44:39.018847 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Nov 12 22:44:39.018856 kernel: random: crng init done
Nov 12 22:44:39.018866 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Nov 12 22:44:39.018875 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Nov 12 22:44:39.018887 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Nov 12 22:44:39.018988 kernel: rtc_cmos 00:04: RTC can wake from S4
Nov 12 22:44:39.019004 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Nov 12 22:44:39.019091 kernel: rtc_cmos 00:04: registered as rtc0
Nov 12 22:44:39.019182 kernel: rtc_cmos 00:04: setting system clock to 2024-11-12T22:44:38 UTC (1731451478)
Nov 12 22:44:39.020938 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram
Nov 12 22:44:39.020955 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
Nov 12 22:44:39.020970 kernel: NET: Registered PF_INET6 protocol family
Nov 12 22:44:39.020979 kernel: Segment Routing with IPv6
Nov 12 22:44:39.020989 kernel: In-situ OAM (IOAM) with IPv6
Nov 12 22:44:39.020998 kernel: NET: Registered PF_PACKET protocol family
Nov 12 22:44:39.021008 kernel: Key type dns_resolver registered
Nov 12 22:44:39.021017 kernel: IPI shorthand broadcast: enabled
Nov 12 22:44:39.021026 kernel: sched_clock: Marking stable (954010681, 130027624)->(1088746875, -4708570)
Nov 12 22:44:39.021036 kernel: registered taskstats version 1
Nov 12 22:44:39.021045 kernel: Loading compiled-in X.509 certificates
Nov 12 22:44:39.021055 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: d04cb2ddbd5c3ca82936c51f5645ef0dcbdcd3b4'
Nov 12 22:44:39.021066 kernel: Key type .fscrypt registered
Nov 12 22:44:39.021075 kernel: Key type fscrypt-provisioning registered
Nov 12 22:44:39.021085 kernel: ima: No TPM chip found, activating TPM-bypass!
Nov 12 22:44:39.021095 kernel: ima: Allocated hash algorithm: sha1
Nov 12 22:44:39.021104 kernel: ima: No architecture policies found
Nov 12 22:44:39.021113 kernel: clk: Disabling unused clocks
Nov 12 22:44:39.021123 kernel: Freeing unused kernel image (initmem) memory: 42968K
Nov 12 22:44:39.021132 kernel: Write protecting the kernel read-only data: 36864k
Nov 12 22:44:39.021144 kernel: Freeing unused kernel image (rodata/data gap) memory: 1840K
Nov 12 22:44:39.021153 kernel: Run /init as init process
Nov 12 22:44:39.021162 kernel:   with arguments:
Nov 12 22:44:39.021171 kernel:     /init
Nov 12 22:44:39.021181 kernel:   with environment:
Nov 12 22:44:39.021207 kernel:     HOME=/
Nov 12 22:44:39.021217 kernel:     TERM=linux
Nov 12 22:44:39.021226 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Nov 12 22:44:39.021239 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)
Nov 12 22:44:39.021254 systemd[1]: Detected virtualization kvm.
Nov 12 22:44:39.021265 systemd[1]: Detected architecture x86-64.
Nov 12 22:44:39.021275 systemd[1]: Running in initrd.
Nov 12 22:44:39.021285 systemd[1]: No hostname configured, using default hostname.
Nov 12 22:44:39.021295 systemd[1]: Hostname set to <localhost>.
Nov 12 22:44:39.021305 systemd[1]: Initializing machine ID from VM UUID.
Nov 12 22:44:39.021315 systemd[1]: Queued start job for default target initrd.target.
Nov 12 22:44:39.021327 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Nov 12 22:44:39.021338 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Nov 12 22:44:39.021349 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Nov 12 22:44:39.021359 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Nov 12 22:44:39.021369 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Nov 12 22:44:39.021379 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Nov 12 22:44:39.021391 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Nov 12 22:44:39.021403 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Nov 12 22:44:39.021414 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Nov 12 22:44:39.021424 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Nov 12 22:44:39.021434 systemd[1]: Reached target paths.target - Path Units.
Nov 12 22:44:39.021454 systemd[1]: Reached target slices.target - Slice Units.
Nov 12 22:44:39.021466 systemd[1]: Reached target swap.target - Swaps.
Nov 12 22:44:39.021478 systemd[1]: Reached target timers.target - Timer Units.
Nov 12 22:44:39.021488 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Nov 12 22:44:39.021499 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Nov 12 22:44:39.021509 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Nov 12 22:44:39.021520 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Nov 12 22:44:39.021530 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Nov 12 22:44:39.021541 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Nov 12 22:44:39.021551 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Nov 12 22:44:39.021565 systemd[1]: Reached target sockets.target - Socket Units.
Nov 12 22:44:39.021576 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Nov 12 22:44:39.021586 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Nov 12 22:44:39.021598 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Nov 12 22:44:39.021608 systemd[1]: Starting systemd-fsck-usr.service...
Nov 12 22:44:39.021618 systemd[1]: Starting systemd-journald.service - Journal Service...
Nov 12 22:44:39.021629 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Nov 12 22:44:39.021660 systemd-journald[186]: Collecting audit messages is disabled.
Nov 12 22:44:39.021687 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:39.021699 systemd-journald[186]: Journal started
Nov 12 22:44:39.021722 systemd-journald[186]: Runtime Journal (/run/log/journal/c2cee9098b52441ca833f5dd5051dbef) is 4.9M, max 39.3M, 34.4M free.
Nov 12 22:44:39.025212 systemd[1]: Started systemd-journald.service - Journal Service.
Nov 12 22:44:39.026116 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Nov 12 22:44:39.028064 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Nov 12 22:44:39.029567 systemd[1]: Finished systemd-fsck-usr.service.
Nov 12 22:44:39.036350 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Nov 12 22:44:39.039317 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Nov 12 22:44:39.055607 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Nov 12 22:44:39.058681 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Nov 12 22:44:39.114437 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Nov 12 22:44:39.114467 kernel: Bridge firewalling registered
Nov 12 22:44:39.072276 systemd-modules-load[187]: Inserted module 'overlay'
Nov 12 22:44:39.107870 systemd-modules-load[187]: Inserted module 'br_netfilter'
Nov 12 22:44:39.116823 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Nov 12 22:44:39.117665 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:39.124387 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Nov 12 22:44:39.126281 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Nov 12 22:44:39.137621 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Nov 12 22:44:39.140804 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Nov 12 22:44:39.154417 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Nov 12 22:44:39.155378 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Nov 12 22:44:39.157024 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Nov 12 22:44:39.173342 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Nov 12 22:44:39.186840 dracut-cmdline[224]: dracut-dracut-053
Nov 12 22:44:39.188480 systemd-resolved[221]: Positive Trust Anchors:
Nov 12 22:44:39.188499 systemd-resolved[221]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Nov 12 22:44:39.188540 systemd-resolved[221]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Nov 12 22:44:39.192791 systemd-resolved[221]: Defaulting to hostname 'linux'.
Nov 12 22:44:39.197408 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Nov 12 22:44:39.199216 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Nov 12 22:44:39.201647 dracut-cmdline[224]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=714367a70d0d672ed3d7ccc2de5247f52d37046778a42409fc8a40b0511373b1
Nov 12 22:44:39.286239 kernel: SCSI subsystem initialized
Nov 12 22:44:39.297314 kernel: Loading iSCSI transport class v2.0-870.
Nov 12 22:44:39.310269 kernel: iscsi: registered transport (tcp)
Nov 12 22:44:39.334459 kernel: iscsi: registered transport (qla4xxx)
Nov 12 22:44:39.334550 kernel: QLogic iSCSI HBA Driver
Nov 12 22:44:39.395520 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Nov 12 22:44:39.403471 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Nov 12 22:44:39.455772 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Nov 12 22:44:39.455903 kernel: device-mapper: uevent: version 1.0.3
Nov 12 22:44:39.461285 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
Nov 12 22:44:39.511340 kernel: raid6: sse2x4   gen() 12260 MB/s
Nov 12 22:44:39.526256 kernel: raid6: sse2x2   gen() 14387 MB/s
Nov 12 22:44:39.543435 kernel: raid6: sse2x1   gen()  9555 MB/s
Nov 12 22:44:39.543502 kernel: raid6: using algorithm sse2x2 gen() 14387 MB/s
Nov 12 22:44:39.561549 kernel: raid6: .... xor() 8731 MB/s, rmw enabled
Nov 12 22:44:39.561634 kernel: raid6: using ssse3x2 recovery algorithm
Nov 12 22:44:39.585251 kernel: xor: measuring software checksum speed
Nov 12 22:44:39.587338 kernel:    prefetch64-sse  : 15528 MB/sec
Nov 12 22:44:39.587399 kernel:    generic_sse     : 15559 MB/sec
Nov 12 22:44:39.587457 kernel: xor: using function: generic_sse (15559 MB/sec)
Nov 12 22:44:39.780969 kernel: Btrfs loaded, zoned=no, fsverity=no
Nov 12 22:44:39.800520 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Nov 12 22:44:39.808389 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Nov 12 22:44:39.857738 systemd-udevd[406]: Using default interface naming scheme 'v255'.
Nov 12 22:44:39.868887 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Nov 12 22:44:39.879462 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Nov 12 22:44:39.910100 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation
Nov 12 22:44:39.956118 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Nov 12 22:44:39.964505 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Nov 12 22:44:40.027185 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Nov 12 22:44:40.039783 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Nov 12 22:44:40.083812 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Nov 12 22:44:40.085977 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Nov 12 22:44:40.087702 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Nov 12 22:44:40.089552 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Nov 12 22:44:40.098517 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Nov 12 22:44:40.117223 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Nov 12 22:44:40.128219 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues
Nov 12 22:44:40.146292 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB)
Nov 12 22:44:40.146428 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Nov 12 22:44:40.146443 kernel: GPT:17805311 != 41943039
Nov 12 22:44:40.146456 kernel: GPT:Alternate GPT header not at the end of the disk.
Nov 12 22:44:40.146477 kernel: GPT:17805311 != 41943039
Nov 12 22:44:40.146489 kernel: GPT: Use GNU Parted to correct GPT errors.
Nov 12 22:44:40.146500 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Nov 12 22:44:40.149506 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Nov 12 22:44:40.149668 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Nov 12 22:44:40.151409 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Nov 12 22:44:40.153503 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Nov 12 22:44:40.153660 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:40.155362 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:40.166555 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:40.193534 kernel: BTRFS: device fsid d498af32-b44b-4318-a942-3a646ccb9d0a devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (464)
Nov 12 22:44:40.202227 kernel: libata version 3.00 loaded.
Nov 12 22:44:40.205780 kernel: ata_piix 0000:00:01.1: version 2.13
Nov 12 22:44:40.209345 kernel: scsi host0: ata_piix
Nov 12 22:44:40.209492 kernel: scsi host1: ata_piix
Nov 12 22:44:40.209617 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
Nov 12 22:44:40.209633 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
Nov 12 22:44:40.211771 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (454)
Nov 12 22:44:40.229583 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT.
Nov 12 22:44:40.258009 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:40.264806 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM.
Nov 12 22:44:40.270261 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132.
Nov 12 22:44:40.270923 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A.
Nov 12 22:44:40.278850 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Nov 12 22:44:40.285519 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Nov 12 22:44:40.289415 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Nov 12 22:44:40.305263 disk-uuid[503]: Primary Header is updated.
Nov 12 22:44:40.305263 disk-uuid[503]: Secondary Entries is updated.
Nov 12 22:44:40.305263 disk-uuid[503]: Secondary Header is updated.
Nov 12 22:44:40.312213 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Nov 12 22:44:40.330777 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Nov 12 22:44:40.367953 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Nov 12 22:44:41.326330 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Nov 12 22:44:41.326460 disk-uuid[505]: The operation has completed successfully.
Nov 12 22:44:41.412920 systemd[1]: disk-uuid.service: Deactivated successfully.
Nov 12 22:44:41.413152 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Nov 12 22:44:41.454468 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Nov 12 22:44:41.461074 sh[524]: Success
Nov 12 22:44:41.482231 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3"
Nov 12 22:44:41.539361 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Nov 12 22:44:41.541409 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Nov 12 22:44:41.542687 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Nov 12 22:44:41.571232 kernel: BTRFS info (device dm-0): first mount of filesystem d498af32-b44b-4318-a942-3a646ccb9d0a
Nov 12 22:44:41.571305 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Nov 12 22:44:41.571319 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead
Nov 12 22:44:41.571332 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Nov 12 22:44:41.574857 kernel: BTRFS info (device dm-0): using free space tree
Nov 12 22:44:41.591814 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Nov 12 22:44:41.593231 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Nov 12 22:44:41.609613 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Nov 12 22:44:41.616674 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Nov 12 22:44:41.636339 kernel: BTRFS info (device vda6): first mount of filesystem 97a326f3-1974-446c-b178-9e746095347a
Nov 12 22:44:41.642702 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Nov 12 22:44:41.642788 kernel: BTRFS info (device vda6): using free space tree
Nov 12 22:44:41.652285 kernel: BTRFS info (device vda6): auto enabling async discard
Nov 12 22:44:41.677320 kernel: BTRFS info (device vda6): last unmount of filesystem 97a326f3-1974-446c-b178-9e746095347a
Nov 12 22:44:41.676534 systemd[1]: mnt-oem.mount: Deactivated successfully.
Nov 12 22:44:41.709987 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Nov 12 22:44:41.719517 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Nov 12 22:44:41.770997 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Nov 12 22:44:41.780407 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Nov 12 22:44:41.803985 systemd-networkd[707]: lo: Link UP
Nov 12 22:44:41.803999 systemd-networkd[707]: lo: Gained carrier
Nov 12 22:44:41.805485 systemd-networkd[707]: Enumeration completed
Nov 12 22:44:41.805810 systemd[1]: Started systemd-networkd.service - Network Configuration.
Nov 12 22:44:41.806013 systemd-networkd[707]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Nov 12 22:44:41.806017 systemd-networkd[707]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Nov 12 22:44:41.807878 systemd-networkd[707]: eth0: Link UP
Nov 12 22:44:41.807887 systemd-networkd[707]: eth0: Gained carrier
Nov 12 22:44:41.807909 systemd-networkd[707]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Nov 12 22:44:41.808574 systemd[1]: Reached target network.target - Network.
Nov 12 22:44:41.824280 systemd-networkd[707]: eth0: DHCPv4 address 172.24.4.8/24, gateway 172.24.4.1 acquired from 172.24.4.1
Nov 12 22:44:42.034240 ignition[647]: Ignition 2.20.0
Nov 12 22:44:42.035368 ignition[647]: Stage: fetch-offline
Nov 12 22:44:42.035586 ignition[647]: no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:42.035615 ignition[647]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:42.039043 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Nov 12 22:44:42.035897 ignition[647]: parsed url from cmdline: ""
Nov 12 22:44:42.035907 ignition[647]: no config URL provided
Nov 12 22:44:42.035921 ignition[647]: reading system config file "/usr/lib/ignition/user.ign"
Nov 12 22:44:42.035942 ignition[647]: no config at "/usr/lib/ignition/user.ign"
Nov 12 22:44:42.035955 ignition[647]: failed to fetch config: resource requires networking
Nov 12 22:44:42.036461 ignition[647]: Ignition finished successfully
Nov 12 22:44:42.049654 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Nov 12 22:44:42.091895 ignition[718]: Ignition 2.20.0
Nov 12 22:44:42.091915 ignition[718]: Stage: fetch
Nov 12 22:44:42.092418 ignition[718]: no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:42.092447 ignition[718]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:42.092677 ignition[718]: parsed url from cmdline: ""
Nov 12 22:44:42.092687 ignition[718]: no config URL provided
Nov 12 22:44:42.092699 ignition[718]: reading system config file "/usr/lib/ignition/user.ign"
Nov 12 22:44:42.092719 ignition[718]: no config at "/usr/lib/ignition/user.ign"
Nov 12 22:44:42.093005 ignition[718]: config drive ("/dev/disk/by-label/config-2") not found. Waiting...
Nov 12 22:44:42.093044 ignition[718]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1
Nov 12 22:44:42.093072 ignition[718]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting...
Nov 12 22:44:42.301069 ignition[718]: GET result: OK
Nov 12 22:44:42.301236 ignition[718]: parsing config with SHA512: 964f33189c0b8b7bb44355c10dbf546f7888f4dd2420c9882008a796fa7b8211c0f46a16240d040bd13473367c1ca225bcc606764cdfae894a39ab85ec351480
Nov 12 22:44:42.321994 unknown[718]: fetched base config from "system"
Nov 12 22:44:42.322017 unknown[718]: fetched base config from "system"
Nov 12 22:44:42.322608 ignition[718]: fetch: fetch complete
Nov 12 22:44:42.322048 unknown[718]: fetched user config from "openstack"
Nov 12 22:44:42.322621 ignition[718]: fetch: fetch passed
Nov 12 22:44:42.328961 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Nov 12 22:44:42.322714 ignition[718]: Ignition finished successfully
Nov 12 22:44:42.340608 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Nov 12 22:44:42.378759 ignition[725]: Ignition 2.20.0
Nov 12 22:44:42.378789 ignition[725]: Stage: kargs
Nov 12 22:44:42.379275 ignition[725]: no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:42.379309 ignition[725]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:42.383710 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Nov 12 22:44:42.381166 ignition[725]: kargs: kargs passed
Nov 12 22:44:42.381353 ignition[725]: Ignition finished successfully
Nov 12 22:44:42.399868 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Nov 12 22:44:42.421439 ignition[731]: Ignition 2.20.0
Nov 12 22:44:42.421469 ignition[731]: Stage: disks
Nov 12 22:44:42.422038 ignition[731]: no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:42.422082 ignition[731]: no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:42.423794 ignition[731]: disks: disks passed
Nov 12 22:44:42.423895 ignition[731]: Ignition finished successfully
Nov 12 22:44:42.426287 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Nov 12 22:44:42.427385 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Nov 12 22:44:42.427960 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Nov 12 22:44:42.428599 systemd[1]: Reached target local-fs.target - Local File Systems.
Nov 12 22:44:42.430500 systemd[1]: Reached target sysinit.target - System Initialization.
Nov 12 22:44:42.432765 systemd[1]: Reached target basic.target - Basic System.
Nov 12 22:44:42.444417 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Nov 12 22:44:42.585602 systemd-fsck[739]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks
Nov 12 22:44:42.612058 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Nov 12 22:44:42.624478 systemd[1]: Mounting sysroot.mount - /sysroot...
Nov 12 22:44:42.804002 kernel: EXT4-fs (vda9): mounted filesystem 62325592-ead9-4e81-b706-99baa0cf9fff r/w with ordered data mode. Quota mode: none.
Nov 12 22:44:42.804115 systemd[1]: Mounted sysroot.mount - /sysroot.
Nov 12 22:44:42.805326 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Nov 12 22:44:42.813425 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Nov 12 22:44:42.817424 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Nov 12 22:44:42.820068 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Nov 12 22:44:42.823822 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent...
Nov 12 22:44:42.824793 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Nov 12 22:44:42.824826 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Nov 12 22:44:42.829752 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Nov 12 22:44:42.835239 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (747)
Nov 12 22:44:42.835702 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Nov 12 22:44:42.843632 kernel: BTRFS info (device vda6): first mount of filesystem 97a326f3-1974-446c-b178-9e746095347a
Nov 12 22:44:42.843668 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Nov 12 22:44:42.843682 kernel: BTRFS info (device vda6): using free space tree
Nov 12 22:44:42.862414 kernel: BTRFS info (device vda6): auto enabling async discard
Nov 12 22:44:42.871397 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Nov 12 22:44:42.948648 initrd-setup-root[775]: cut: /sysroot/etc/passwd: No such file or directory
Nov 12 22:44:42.958860 initrd-setup-root[782]: cut: /sysroot/etc/group: No such file or directory
Nov 12 22:44:42.967091 initrd-setup-root[789]: cut: /sysroot/etc/shadow: No such file or directory
Nov 12 22:44:42.971949 initrd-setup-root[796]: cut: /sysroot/etc/gshadow: No such file or directory
Nov 12 22:44:43.096662 systemd-networkd[707]: eth0: Gained IPv6LL
Nov 12 22:44:43.099565 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Nov 12 22:44:43.105291 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Nov 12 22:44:43.107356 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Nov 12 22:44:43.126338 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Nov 12 22:44:43.131874 kernel: BTRFS info (device vda6): last unmount of filesystem 97a326f3-1974-446c-b178-9e746095347a
Nov 12 22:44:43.146043 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Nov 12 22:44:43.164866 ignition[865]: INFO     : Ignition 2.20.0
Nov 12 22:44:43.164866 ignition[865]: INFO     : Stage: mount
Nov 12 22:44:43.168121 ignition[865]: INFO     : no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:43.168121 ignition[865]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:43.168121 ignition[865]: INFO     : mount: mount passed
Nov 12 22:44:43.168121 ignition[865]: INFO     : Ignition finished successfully
Nov 12 22:44:43.166951 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Nov 12 22:44:50.054676 coreos-metadata[749]: Nov 12 22:44:50.054 WARN failed to locate config-drive, using the metadata service API instead
Nov 12 22:44:50.097788 coreos-metadata[749]: Nov 12 22:44:50.097 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1
Nov 12 22:44:50.115469 coreos-metadata[749]: Nov 12 22:44:50.115 INFO Fetch successful
Nov 12 22:44:50.116947 coreos-metadata[749]: Nov 12 22:44:50.116 INFO wrote hostname ci-4152-0-0-8-6620d9a96b.novalocal to /sysroot/etc/hostname
Nov 12 22:44:50.119564 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully.
Nov 12 22:44:50.119773 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent.
Nov 12 22:44:50.133396 systemd[1]: Starting ignition-files.service - Ignition (files)...
Nov 12 22:44:50.170570 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Nov 12 22:44:50.192256 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (881)
Nov 12 22:44:50.200790 kernel: BTRFS info (device vda6): first mount of filesystem 97a326f3-1974-446c-b178-9e746095347a
Nov 12 22:44:50.200869 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Nov 12 22:44:50.200898 kernel: BTRFS info (device vda6): using free space tree
Nov 12 22:44:50.211277 kernel: BTRFS info (device vda6): auto enabling async discard
Nov 12 22:44:50.216452 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Nov 12 22:44:50.259477 ignition[899]: INFO     : Ignition 2.20.0
Nov 12 22:44:50.262421 ignition[899]: INFO     : Stage: files
Nov 12 22:44:50.262421 ignition[899]: INFO     : no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:50.262421 ignition[899]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:50.267367 ignition[899]: DEBUG    : files: compiled without relabeling support, skipping
Nov 12 22:44:50.267367 ignition[899]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Nov 12 22:44:50.267367 ignition[899]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Nov 12 22:44:50.272783 ignition[899]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Nov 12 22:44:50.272783 ignition[899]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Nov 12 22:44:50.272783 ignition[899]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Nov 12 22:44:50.272377 unknown[899]: wrote ssh authorized keys file for user: core
Nov 12 22:44:50.279783 ignition[899]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Nov 12 22:44:50.279783 ignition[899]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Nov 12 22:44:50.279783 ignition[899]: INFO     : files: op(4): [started]  processing unit "coreos-metadata.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(4): op(5): [started]  writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/20-clct-provider-override.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(4): [finished] processing unit "coreos-metadata.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(6): [started]  processing unit "coreos-metadata-sshkeys@.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(6): op(7): [started]  writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(6): op(7): [finished] writing systemd drop-in "20-clct-provider-override.conf" at "/sysroot/etc/systemd/system/coreos-metadata-sshkeys@.service.d/20-clct-provider-override.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(6): [finished] processing unit "coreos-metadata-sshkeys@.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(8): [started]  processing unit "etcd-member.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(8): op(9): [started]  writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(8): op(9): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(8): [finished] processing unit "etcd-member.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(a): [started]  setting preset to enabled for "coreos-metadata-sshkeys@.service "
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(a): [finished] setting preset to enabled for "coreos-metadata-sshkeys@.service "
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(b): [started]  setting preset to enabled for "etcd-member.service"
Nov 12 22:44:50.287876 ignition[899]: INFO     : files: op(b): [finished] setting preset to enabled for "etcd-member.service"
Nov 12 22:44:50.304110 systemd[1]: Finished ignition-files.service - Ignition (files).
Nov 12 22:44:50.321369 ignition[899]: INFO     : files: createResultFile: createFiles: op(c): [started]  writing file "/sysroot/etc/.ignition-result.json"
Nov 12 22:44:50.321369 ignition[899]: INFO     : files: createResultFile: createFiles: op(c): [finished] writing file "/sysroot/etc/.ignition-result.json"
Nov 12 22:44:50.321369 ignition[899]: INFO     : files: files passed
Nov 12 22:44:50.321369 ignition[899]: INFO     : Ignition finished successfully
Nov 12 22:44:50.316339 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Nov 12 22:44:50.319392 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Nov 12 22:44:50.321120 systemd[1]: ignition-quench.service: Deactivated successfully.
Nov 12 22:44:50.322258 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Nov 12 22:44:50.338077 initrd-setup-root-after-ignition[928]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Nov 12 22:44:50.338077 initrd-setup-root-after-ignition[928]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Nov 12 22:44:50.340896 initrd-setup-root-after-ignition[932]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Nov 12 22:44:50.342439 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Nov 12 22:44:50.344412 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Nov 12 22:44:50.348361 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Nov 12 22:44:50.371426 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Nov 12 22:44:50.371554 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Nov 12 22:44:50.373248 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Nov 12 22:44:50.374058 systemd[1]: Reached target initrd.target - Initrd Default Target.
Nov 12 22:44:50.375307 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Nov 12 22:44:50.377370 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Nov 12 22:44:50.395043 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Nov 12 22:44:50.400329 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Nov 12 22:44:50.411590 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Nov 12 22:44:50.412854 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Nov 12 22:44:50.413507 systemd[1]: Stopped target timers.target - Timer Units.
Nov 12 22:44:50.414760 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Nov 12 22:44:50.414875 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Nov 12 22:44:50.416078 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Nov 12 22:44:50.416808 systemd[1]: Stopped target basic.target - Basic System.
Nov 12 22:44:50.421885 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Nov 12 22:44:50.422950 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Nov 12 22:44:50.423952 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Nov 12 22:44:50.425127 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Nov 12 22:44:50.426331 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Nov 12 22:44:50.427552 systemd[1]: Stopped target sysinit.target - System Initialization.
Nov 12 22:44:50.428716 systemd[1]: Stopped target local-fs.target - Local File Systems.
Nov 12 22:44:50.429936 systemd[1]: Stopped target swap.target - Swaps.
Nov 12 22:44:50.430920 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Nov 12 22:44:50.431033 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Nov 12 22:44:50.432210 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Nov 12 22:44:50.432951 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Nov 12 22:44:50.433883 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Nov 12 22:44:50.433985 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Nov 12 22:44:50.435061 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Nov 12 22:44:50.435172 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Nov 12 22:44:50.436649 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Nov 12 22:44:50.436772 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Nov 12 22:44:50.437501 systemd[1]: ignition-files.service: Deactivated successfully.
Nov 12 22:44:50.437653 systemd[1]: Stopped ignition-files.service - Ignition (files).
Nov 12 22:44:50.447636 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Nov 12 22:44:50.448161 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Nov 12 22:44:50.448357 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Nov 12 22:44:50.450431 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Nov 12 22:44:50.453661 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Nov 12 22:44:50.454142 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Nov 12 22:44:50.455316 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Nov 12 22:44:50.455427 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Nov 12 22:44:50.463871 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Nov 12 22:44:50.463972 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Nov 12 22:44:50.472257 ignition[952]: INFO     : Ignition 2.20.0
Nov 12 22:44:50.472257 ignition[952]: INFO     : Stage: umount
Nov 12 22:44:50.472257 ignition[952]: INFO     : no configs at "/usr/lib/ignition/base.d"
Nov 12 22:44:50.472257 ignition[952]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/openstack"
Nov 12 22:44:50.475600 ignition[952]: INFO     : umount: umount passed
Nov 12 22:44:50.475600 ignition[952]: INFO     : Ignition finished successfully
Nov 12 22:44:50.477526 systemd[1]: ignition-mount.service: Deactivated successfully.
Nov 12 22:44:50.478391 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Nov 12 22:44:50.479749 systemd[1]: ignition-disks.service: Deactivated successfully.
Nov 12 22:44:50.480298 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Nov 12 22:44:50.481583 systemd[1]: ignition-kargs.service: Deactivated successfully.
Nov 12 22:44:50.481626 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Nov 12 22:44:50.482470 systemd[1]: ignition-fetch.service: Deactivated successfully.
Nov 12 22:44:50.482514 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Nov 12 22:44:50.483124 systemd[1]: Stopped target network.target - Network.
Nov 12 22:44:50.483754 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Nov 12 22:44:50.483816 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Nov 12 22:44:50.486276 systemd[1]: Stopped target paths.target - Path Units.
Nov 12 22:44:50.486980 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Nov 12 22:44:50.490397 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Nov 12 22:44:50.491108 systemd[1]: Stopped target slices.target - Slice Units.
Nov 12 22:44:50.491624 systemd[1]: Stopped target sockets.target - Socket Units.
Nov 12 22:44:50.492156 systemd[1]: iscsid.socket: Deactivated successfully.
Nov 12 22:44:50.492229 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Nov 12 22:44:50.493460 systemd[1]: iscsiuio.socket: Deactivated successfully.
Nov 12 22:44:50.493494 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Nov 12 22:44:50.494509 systemd[1]: ignition-setup.service: Deactivated successfully.
Nov 12 22:44:50.494554 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Nov 12 22:44:50.495571 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Nov 12 22:44:50.495614 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Nov 12 22:44:50.496731 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Nov 12 22:44:50.497866 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Nov 12 22:44:50.500015 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Nov 12 22:44:50.500580 systemd[1]: sysroot-boot.service: Deactivated successfully.
Nov 12 22:44:50.500675 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Nov 12 22:44:50.501969 systemd-networkd[707]: eth0: DHCPv6 lease lost
Nov 12 22:44:50.507548 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Nov 12 22:44:50.507635 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Nov 12 22:44:50.509047 systemd[1]: systemd-resolved.service: Deactivated successfully.
Nov 12 22:44:50.509172 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Nov 12 22:44:50.511514 systemd[1]: systemd-networkd.service: Deactivated successfully.
Nov 12 22:44:50.511891 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Nov 12 22:44:50.514614 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Nov 12 22:44:50.514676 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Nov 12 22:44:50.523325 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Nov 12 22:44:50.523804 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Nov 12 22:44:50.523868 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Nov 12 22:44:50.524449 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Nov 12 22:44:50.524493 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Nov 12 22:44:50.525038 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Nov 12 22:44:50.525079 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Nov 12 22:44:50.526240 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Nov 12 22:44:50.526287 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Nov 12 22:44:50.527606 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Nov 12 22:44:50.535112 systemd[1]: systemd-udevd.service: Deactivated successfully.
Nov 12 22:44:50.535344 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Nov 12 22:44:50.536346 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Nov 12 22:44:50.536393 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Nov 12 22:44:50.537003 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Nov 12 22:44:50.537039 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Nov 12 22:44:50.537668 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Nov 12 22:44:50.537718 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Nov 12 22:44:50.543644 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Nov 12 22:44:50.543708 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Nov 12 22:44:50.545018 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Nov 12 22:44:50.545064 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Nov 12 22:44:50.553486 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Nov 12 22:44:50.555340 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Nov 12 22:44:50.555414 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Nov 12 22:44:50.555995 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Nov 12 22:44:50.556042 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:50.556928 systemd[1]: network-cleanup.service: Deactivated successfully.
Nov 12 22:44:50.557035 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Nov 12 22:44:50.561897 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Nov 12 22:44:50.562011 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Nov 12 22:44:50.563716 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Nov 12 22:44:50.569371 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Nov 12 22:44:50.586582 systemd[1]: Switching root.
Nov 12 22:44:50.619753 systemd-journald[186]: Journal stopped
Nov 12 22:44:52.087004 systemd-journald[186]: Received SIGTERM from PID 1 (systemd).
Nov 12 22:44:52.087070 kernel: SELinux:  policy capability network_peer_controls=1
Nov 12 22:44:52.087086 kernel: SELinux:  policy capability open_perms=1
Nov 12 22:44:52.087099 kernel: SELinux:  policy capability extended_socket_class=1
Nov 12 22:44:52.087111 kernel: SELinux:  policy capability always_check_network=0
Nov 12 22:44:52.087122 kernel: SELinux:  policy capability cgroup_seclabel=1
Nov 12 22:44:52.087134 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Nov 12 22:44:52.087149 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Nov 12 22:44:52.087164 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Nov 12 22:44:52.087180 kernel: audit: type=1403 audit(1731451490.765:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Nov 12 22:44:52.091235 systemd[1]: Successfully loaded SELinux policy in 68.934ms.
Nov 12 22:44:52.091260 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 11.299ms.
Nov 12 22:44:52.091275 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)
Nov 12 22:44:52.091288 systemd[1]: Detected virtualization kvm.
Nov 12 22:44:52.091304 systemd[1]: Detected architecture x86-64.
Nov 12 22:44:52.091317 systemd[1]: Detected first boot.
Nov 12 22:44:52.091329 systemd[1]: Hostname set to <ci-4152-0-0-8-6620d9a96b.novalocal>.
Nov 12 22:44:52.091341 systemd[1]: Initializing machine ID from VM UUID.
Nov 12 22:44:52.091353 zram_generator::config[996]: No configuration found.
Nov 12 22:44:52.091366 systemd[1]: Populated /etc with preset unit settings.
Nov 12 22:44:52.091378 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Nov 12 22:44:52.091389 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Nov 12 22:44:52.091404 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Nov 12 22:44:52.091416 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Nov 12 22:44:52.091429 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Nov 12 22:44:52.091441 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys.
Nov 12 22:44:52.091453 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Nov 12 22:44:52.091465 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Nov 12 22:44:52.091478 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Nov 12 22:44:52.091490 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Nov 12 22:44:52.091505 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Nov 12 22:44:52.091521 systemd[1]: Created slice user.slice - User and Session Slice.
Nov 12 22:44:52.091534 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Nov 12 22:44:52.091547 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Nov 12 22:44:52.091559 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Nov 12 22:44:52.091571 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Nov 12 22:44:52.091584 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Nov 12 22:44:52.091596 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Nov 12 22:44:52.091609 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Nov 12 22:44:52.091624 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Nov 12 22:44:52.091636 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Nov 12 22:44:52.091648 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Nov 12 22:44:52.091660 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Nov 12 22:44:52.091673 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Nov 12 22:44:52.091685 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Nov 12 22:44:52.091711 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Nov 12 22:44:52.091724 systemd[1]: Reached target slices.target - Slice Units.
Nov 12 22:44:52.091736 systemd[1]: Reached target swap.target - Swaps.
Nov 12 22:44:52.091749 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Nov 12 22:44:52.091761 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Nov 12 22:44:52.091773 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Nov 12 22:44:52.091785 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Nov 12 22:44:52.091797 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Nov 12 22:44:52.091809 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Nov 12 22:44:52.091821 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Nov 12 22:44:52.091836 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Nov 12 22:44:52.091848 systemd[1]: Mounting media.mount - External Media Directory...
Nov 12 22:44:52.091860 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:52.091872 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Nov 12 22:44:52.091891 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Nov 12 22:44:52.091903 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Nov 12 22:44:52.091915 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).
Nov 12 22:44:52.091927 systemd[1]: Reached target machines.target - Containers.
Nov 12 22:44:52.091941 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Nov 12 22:44:52.091953 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Nov 12 22:44:52.091966 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Nov 12 22:44:52.091978 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Nov 12 22:44:52.091992 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Nov 12 22:44:52.092004 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Nov 12 22:44:52.092016 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Nov 12 22:44:52.092028 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Nov 12 22:44:52.092041 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Nov 12 22:44:52.092056 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Nov 12 22:44:52.092068 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Nov 12 22:44:52.092080 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Nov 12 22:44:52.092091 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Nov 12 22:44:52.092103 systemd[1]: Stopped systemd-fsck-usr.service.
Nov 12 22:44:52.092115 systemd[1]: Starting systemd-journald.service - Journal Service...
Nov 12 22:44:52.092127 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Nov 12 22:44:52.092149 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Nov 12 22:44:52.092183 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Nov 12 22:44:52.092972 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Nov 12 22:44:52.092988 systemd[1]: verity-setup.service: Deactivated successfully.
Nov 12 22:44:52.093000 systemd[1]: Stopped verity-setup.service.
Nov 12 22:44:52.093013 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:52.093025 kernel: loop: module loaded
Nov 12 22:44:52.093039 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Nov 12 22:44:52.093050 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Nov 12 22:44:52.093063 systemd[1]: Mounted media.mount - External Media Directory.
Nov 12 22:44:52.093079 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Nov 12 22:44:52.093092 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Nov 12 22:44:52.093104 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Nov 12 22:44:52.093117 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Nov 12 22:44:52.093130 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Nov 12 22:44:52.093146 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Nov 12 22:44:52.093182 systemd-journald[1086]: Collecting audit messages is disabled.
Nov 12 22:44:52.093222 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Nov 12 22:44:52.093236 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Nov 12 22:44:52.093248 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Nov 12 22:44:52.093264 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Nov 12 22:44:52.093277 systemd-journald[1086]: Journal started
Nov 12 22:44:52.093301 systemd-journald[1086]: Runtime Journal (/run/log/journal/c2cee9098b52441ca833f5dd5051dbef) is 4.9M, max 39.3M, 34.4M free.
Nov 12 22:44:51.745575 systemd[1]: Queued start job for default target multi-user.target.
Nov 12 22:44:51.776454 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6.
Nov 12 22:44:51.776927 systemd[1]: systemd-journald.service: Deactivated successfully.
Nov 12 22:44:52.101264 systemd[1]: Started systemd-journald.service - Journal Service.
Nov 12 22:44:52.099799 systemd[1]: modprobe@loop.service: Deactivated successfully.
Nov 12 22:44:52.100331 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Nov 12 22:44:52.101381 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Nov 12 22:44:52.102606 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Nov 12 22:44:52.103577 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Nov 12 22:44:52.112655 kernel: fuse: init (API version 7.39)
Nov 12 22:44:52.117142 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Nov 12 22:44:52.118135 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Nov 12 22:44:52.118398 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Nov 12 22:44:52.123925 systemd[1]: Reached target network-pre.target - Preparation for Network.
Nov 12 22:44:52.134295 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Nov 12 22:44:52.141156 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Nov 12 22:44:52.141821 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Nov 12 22:44:52.141860 systemd[1]: Reached target local-fs.target - Local File Systems.
Nov 12 22:44:52.145885 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink).
Nov 12 22:44:52.151424 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Nov 12 22:44:52.155341 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Nov 12 22:44:52.156052 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Nov 12 22:44:52.163407 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Nov 12 22:44:52.166332 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Nov 12 22:44:52.167325 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Nov 12 22:44:52.172388 kernel: ACPI: bus type drm_connector registered
Nov 12 22:44:52.174487 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Nov 12 22:44:52.175089 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Nov 12 22:44:52.178325 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Nov 12 22:44:52.184375 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Nov 12 22:44:52.190347 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Nov 12 22:44:52.192591 systemd[1]: modprobe@drm.service: Deactivated successfully.
Nov 12 22:44:52.192772 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Nov 12 22:44:52.193586 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Nov 12 22:44:52.195517 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Nov 12 22:44:52.196283 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Nov 12 22:44:52.212637 systemd-journald[1086]: Time spent on flushing to /var/log/journal/c2cee9098b52441ca833f5dd5051dbef is 71.720ms for 929 entries.
Nov 12 22:44:52.212637 systemd-journald[1086]: System Journal (/var/log/journal/c2cee9098b52441ca833f5dd5051dbef) is 8.0M, max 584.8M, 576.8M free.
Nov 12 22:44:52.298884 systemd-journald[1086]: Received client request to flush runtime journal.
Nov 12 22:44:52.299618 kernel: loop0: detected capacity change from 0 to 8
Nov 12 22:44:52.299641 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Nov 12 22:44:52.240904 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Nov 12 22:44:52.244269 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Nov 12 22:44:52.244979 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Nov 12 22:44:52.255417 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk...
Nov 12 22:44:52.290640 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Nov 12 22:44:52.300888 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
Nov 12 22:44:52.307542 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Nov 12 22:44:52.309254 kernel: loop1: detected capacity change from 0 to 140992
Nov 12 22:44:52.334947 udevadm[1143]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in.
Nov 12 22:44:52.351290 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Nov 12 22:44:52.360400 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Nov 12 22:44:52.368369 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Nov 12 22:44:52.369481 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk.
Nov 12 22:44:52.407078 systemd-tmpfiles[1147]: ACLs are not supported, ignoring.
Nov 12 22:44:52.407099 systemd-tmpfiles[1147]: ACLs are not supported, ignoring.
Nov 12 22:44:52.410215 kernel: loop2: detected capacity change from 0 to 138184
Nov 12 22:44:52.411888 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Nov 12 22:44:52.481309 kernel: loop3: detected capacity change from 0 to 8
Nov 12 22:44:52.483234 kernel: loop4: detected capacity change from 0 to 140992
Nov 12 22:44:52.515228 kernel: loop5: detected capacity change from 0 to 138184
Nov 12 22:44:52.558180 (sd-merge)[1153]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'.
Nov 12 22:44:52.559803 (sd-merge)[1153]: Merged extensions into '/usr'.
Nov 12 22:44:52.563690 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Nov 12 22:44:52.577742 systemd[1]: Starting ensure-sysext.service...
Nov 12 22:44:52.585361 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Nov 12 22:44:52.603410 systemd[1]: Reloading requested from client PID 1155 ('systemctl') (unit ensure-sysext.service)...
Nov 12 22:44:52.603426 systemd[1]: Reloading...
Nov 12 22:44:52.644890 systemd-tmpfiles[1156]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Nov 12 22:44:52.649161 systemd-tmpfiles[1156]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Nov 12 22:44:52.653625 systemd-tmpfiles[1156]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Nov 12 22:44:52.654158 systemd-tmpfiles[1156]: ACLs are not supported, ignoring.
Nov 12 22:44:52.654346 systemd-tmpfiles[1156]: ACLs are not supported, ignoring.
Nov 12 22:44:52.672841 systemd-tmpfiles[1156]: Detected autofs mount point /boot during canonicalization of boot.
Nov 12 22:44:52.675068 systemd-tmpfiles[1156]: Skipping /boot
Nov 12 22:44:52.713828 systemd-tmpfiles[1156]: Detected autofs mount point /boot during canonicalization of boot.
Nov 12 22:44:52.716255 systemd-tmpfiles[1156]: Skipping /boot
Nov 12 22:44:52.723746 zram_generator::config[1182]: No configuration found.
Nov 12 22:44:52.954021 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.
Nov 12 22:44:53.013024 systemd[1]: Reloading finished in 409 ms.
Nov 12 22:44:53.041541 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Nov 12 22:44:53.060372 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Nov 12 22:44:53.063495 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Nov 12 22:44:53.072427 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Nov 12 22:44:53.083127 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Nov 12 22:44:53.088793 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Nov 12 22:44:53.096725 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.098485 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Nov 12 22:44:53.105890 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Nov 12 22:44:53.109516 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Nov 12 22:44:53.119312 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Nov 12 22:44:53.119984 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Nov 12 22:44:53.120123 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.125635 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.125840 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Nov 12 22:44:53.126006 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Nov 12 22:44:53.126114 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.135785 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Nov 12 22:44:53.145176 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.147370 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Nov 12 22:44:53.155299 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Nov 12 22:44:53.156363 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Nov 12 22:44:53.157463 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Nov 12 22:44:53.160560 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Nov 12 22:44:53.160701 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Nov 12 22:44:53.163233 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Nov 12 22:44:53.163389 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Nov 12 22:44:53.165049 systemd[1]: Finished ensure-sysext.service.
Nov 12 22:44:53.165872 systemd[1]: modprobe@drm.service: Deactivated successfully.
Nov 12 22:44:53.165989 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Nov 12 22:44:53.167591 systemd[1]: modprobe@loop.service: Deactivated successfully.
Nov 12 22:44:53.168237 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Nov 12 22:44:53.183004 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Nov 12 22:44:53.183175 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Nov 12 22:44:53.192440 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization...
Nov 12 22:44:53.194441 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Nov 12 22:44:53.229995 ldconfig[1123]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Nov 12 22:44:53.239382 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Nov 12 22:44:53.331380 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Nov 12 22:44:53.349841 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization.
Nov 12 22:44:53.350994 systemd-resolved[1244]: Positive Trust Anchors:
Nov 12 22:44:53.351021 systemd-resolved[1244]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Nov 12 22:44:53.351076 systemd[1]: Reached target time-set.target - System Time Set.
Nov 12 22:44:53.351099 systemd-resolved[1244]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Nov 12 22:44:53.353336 augenrules[1284]: No rules
Nov 12 22:44:53.355596 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Nov 12 22:44:53.356512 systemd[1]: audit-rules.service: Deactivated successfully.
Nov 12 22:44:53.356721 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Nov 12 22:44:53.384100 systemd-resolved[1244]: Using system hostname 'ci-4152-0-0-8-6620d9a96b.novalocal'.
Nov 12 22:44:53.385523 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Nov 12 22:44:53.386353 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Nov 12 22:44:53.433010 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Nov 12 22:44:53.452351 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Nov 12 22:44:53.454349 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Nov 12 22:44:53.471311 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Nov 12 22:44:53.489209 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Nov 12 22:44:53.490368 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).
Nov 12 22:44:53.524142 systemd-udevd[1293]: Using default interface naming scheme 'v255'.
Nov 12 22:44:53.563886 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Nov 12 22:44:53.580131 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Nov 12 22:44:53.625263 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1298)
Nov 12 22:44:53.648346 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Nov 12 22:44:53.694375 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Nov 12 22:44:53.694456 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1298)
Nov 12 22:44:53.699237 kernel: ACPI: button: Power Button [PWRF]
Nov 12 22:44:53.726708 systemd-networkd[1307]: lo: Link UP
Nov 12 22:44:53.726718 systemd-networkd[1307]: lo: Gained carrier
Nov 12 22:44:53.728609 systemd-networkd[1307]: Enumeration completed
Nov 12 22:44:53.728718 systemd[1]: Started systemd-networkd.service - Network Configuration.
Nov 12 22:44:53.729435 systemd-networkd[1307]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Nov 12 22:44:53.729446 systemd-networkd[1307]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Nov 12 22:44:53.729635 systemd[1]: Reached target network.target - Network.
Nov 12 22:44:53.731279 systemd-networkd[1307]: eth0: Link UP
Nov 12 22:44:53.731290 systemd-networkd[1307]: eth0: Gained carrier
Nov 12 22:44:53.731306 systemd-networkd[1307]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Nov 12 22:44:53.738267 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1313)
Nov 12 22:44:53.739398 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Nov 12 22:44:53.746240 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
Nov 12 22:44:53.747267 systemd-networkd[1307]: eth0: DHCPv4 address 172.24.4.8/24, gateway 172.24.4.1 acquired from 172.24.4.1
Nov 12 22:44:53.748328 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:53.799239 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Nov 12 22:44:53.824236 kernel: mousedev: PS/2 mouse device common for all mice
Nov 12 22:44:53.831556 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:53.844094 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0
Nov 12 22:44:53.844161 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console
Nov 12 22:44:53.845825 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Nov 12 22:44:53.849534 kernel: Console: switching to colour dummy device 80x25
Nov 12 22:44:53.856623 kernel: [drm] features: -virgl +edid -resource_blob -host_visible
Nov 12 22:44:53.857297 kernel: [drm] features: -context_init
Nov 12 22:44:53.859517 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Nov 12 22:44:53.860232 kernel: [drm] number of scanouts: 1
Nov 12 22:44:53.860265 kernel: [drm] number of cap sets: 0
Nov 12 22:44:53.865804 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0
Nov 12 22:44:53.871470 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Nov 12 22:44:53.872652 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:53.878338 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device
Nov 12 22:44:53.878406 kernel: Console: switching to colour frame buffer device 128x48
Nov 12 22:44:53.882433 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:53.886350 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device
Nov 12 22:44:53.889618 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Nov 12 22:44:53.902946 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Nov 12 22:44:53.903139 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:53.905688 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
Nov 12 22:44:53.912354 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes...
Nov 12 22:44:53.914011 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Nov 12 22:44:53.929313 lvm[1336]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Nov 12 22:44:53.962954 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes.
Nov 12 22:44:53.963220 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Nov 12 22:44:53.968540 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes...
Nov 12 22:44:53.987278 lvm[1341]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Nov 12 22:44:54.014497 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes.
Nov 12 22:44:54.019021 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Nov 12 22:44:54.019660 systemd[1]: Reached target sysinit.target - System Initialization.
Nov 12 22:44:54.019944 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Nov 12 22:44:54.020131 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Nov 12 22:44:54.021852 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Nov 12 22:44:54.022170 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Nov 12 22:44:54.022353 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Nov 12 22:44:54.022463 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Nov 12 22:44:54.022503 systemd[1]: Reached target paths.target - Path Units.
Nov 12 22:44:54.022601 systemd[1]: Reached target timers.target - Timer Units.
Nov 12 22:44:54.025313 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Nov 12 22:44:54.027827 systemd[1]: Starting docker.socket - Docker Socket for the API...
Nov 12 22:44:54.038321 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Nov 12 22:44:54.044293 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Nov 12 22:44:54.050726 systemd[1]: Reached target sockets.target - Socket Units.
Nov 12 22:44:54.053861 systemd[1]: Reached target basic.target - Basic System.
Nov 12 22:44:54.054863 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Nov 12 22:44:54.054916 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Nov 12 22:44:54.063402 systemd[1]: Starting containerd.service - containerd container runtime...
Nov 12 22:44:54.079568 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)...
Nov 12 22:44:54.098578 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Nov 12 22:44:54.114580 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Nov 12 22:44:54.122460 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Nov 12 22:44:54.136975 coreos-metadata[1349]: Nov 12 22:44:54.136 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1
Nov 12 22:44:54.138142 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Nov 12 22:44:54.141955 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).
Nov 12 22:44:54.144767 jq[1354]: false
Nov 12 22:44:54.155483 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Nov 12 22:44:54.158670 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Nov 12 22:44:54.169443 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Nov 12 22:44:54.172309 coreos-metadata[1349]: Nov 12 22:44:54.172 INFO Fetch successful
Nov 12 22:44:54.172468 coreos-metadata[1349]: Nov 12 22:44:54.172 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1
Nov 12 22:44:54.179565 coreos-metadata[1350]: Nov 12 22:44:54.179 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1
Nov 12 22:44:54.180906 systemd[1]: Starting systemd-logind.service - User Login Management...
Nov 12 22:44:54.186149 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Nov 12 22:44:54.186843 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Nov 12 22:44:54.190725 coreos-metadata[1349]: Nov 12 22:44:54.190 INFO Fetch successful
Nov 12 22:44:54.190440 systemd[1]: Starting update-engine.service - Update Engine...
Nov 12 22:44:54.195394 unknown[1349]: wrote ssh authorized keys file for user: core
Nov 12 22:44:54.199340 coreos-metadata[1350]: Nov 12 22:44:54.199 INFO Fetch successful
Nov 12 22:44:54.199340 coreos-metadata[1350]: Nov 12 22:44:54.199 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1
Nov 12 22:44:54.200388 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Nov 12 22:44:54.212701 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Nov 12 22:44:54.212888 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Nov 12 22:44:54.213182 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Nov 12 22:44:54.213373 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Nov 12 22:44:54.214371 coreos-metadata[1350]: Nov 12 22:44:54.214 INFO Fetch successful
Nov 12 22:44:54.214371 coreos-metadata[1350]: Nov 12 22:44:54.214 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1
Nov 12 22:44:54.228729 coreos-metadata[1350]: Nov 12 22:44:54.228 INFO Fetch successful
Nov 12 22:44:54.228729 coreos-metadata[1350]: Nov 12 22:44:54.228 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1
Nov 12 22:44:54.232042 dbus-daemon[1353]: [system] SELinux support is enabled
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found loop3
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found loop4
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found loop5
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found vda
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found vda1
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found vda2
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found vda3
Nov 12 22:44:54.251743 extend-filesystems[1355]: Found usr
Nov 12 22:44:54.298010 coreos-metadata[1350]: Nov 12 22:44:54.244 INFO Fetch successful
Nov 12 22:44:54.298010 coreos-metadata[1350]: Nov 12 22:44:54.245 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1
Nov 12 22:44:54.298010 coreos-metadata[1350]: Nov 12 22:44:54.261 INFO Fetch successful
Nov 12 22:44:54.298010 coreos-metadata[1350]: Nov 12 22:44:54.261 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1
Nov 12 22:44:54.298010 coreos-metadata[1350]: Nov 12 22:44:54.279 INFO Fetch successful
Nov 12 22:44:54.235618 systemd[1]: motdgen.service: Deactivated successfully.
Nov 12 22:44:54.301260 update_engine[1365]: I20241112 22:44:54.270139  1365 main.cc:92] Flatcar Update Engine starting
Nov 12 22:44:54.301260 update_engine[1365]: I20241112 22:44:54.271998  1365 update_check_scheduler.cc:74] Next update check in 7m40s
Nov 12 22:44:54.301679 extend-filesystems[1355]: Found vda4
Nov 12 22:44:54.301679 extend-filesystems[1355]: Found vda6
Nov 12 22:44:54.301679 extend-filesystems[1355]: Found vda7
Nov 12 22:44:54.301679 extend-filesystems[1355]: Found vda9
Nov 12 22:44:54.301679 extend-filesystems[1355]: Checking size of /dev/vda9
Nov 12 22:44:54.236279 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Nov 12 22:44:54.320087 jq[1369]: true
Nov 12 22:44:54.238048 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Nov 12 22:44:54.262879 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).
Nov 12 22:44:54.262943 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Nov 12 22:44:54.273661 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).
Nov 12 22:44:54.273692 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Nov 12 22:44:54.278486 systemd[1]: Started update-engine.service - Update Engine.
Nov 12 22:44:54.289415 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Nov 12 22:44:54.302595 (ntainerd)[1377]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Nov 12 22:44:54.328641 extend-filesystems[1355]: Resized partition /dev/vda9
Nov 12 22:44:54.336815 update-ssh-keys[1371]: Updated "/home/core/.ssh/authorized_keys"
Nov 12 22:44:54.348676 extend-filesystems[1392]: resize2fs 1.47.1 (20-May-2024)
Nov 12 22:44:54.351020 jq[1387]: true
Nov 12 22:44:54.349279 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys).
Nov 12 22:44:54.366004 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks
Nov 12 22:44:54.360736 systemd-logind[1362]: New seat seat0.
Nov 12 22:44:54.367750 systemd-logind[1362]: Watching system buttons on /dev/input/event1 (Power Button)
Nov 12 22:44:54.367771 systemd-logind[1362]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Nov 12 22:44:54.368162 systemd[1]: Started systemd-logind.service - User Login Management.
Nov 12 22:44:54.391546 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Nov 12 22:44:54.398640 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Nov 12 22:44:54.412553 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1308)
Nov 12 22:44:54.512109 locksmithd[1384]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Nov 12 22:44:54.545885 kernel: EXT4-fs (vda9): resized filesystem to 4635643
Nov 12 22:44:54.623522 extend-filesystems[1392]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required
Nov 12 22:44:54.623522 extend-filesystems[1392]: old_desc_blocks = 1, new_desc_blocks = 3
Nov 12 22:44:54.623522 extend-filesystems[1392]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long.
Nov 12 22:44:54.642688 extend-filesystems[1355]: Resized filesystem in /dev/vda9
Nov 12 22:44:54.625750 systemd[1]: extend-filesystems.service: Deactivated successfully.
Nov 12 22:44:54.625933 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Nov 12 22:44:54.648479 bash[1413]: Updated "/home/core/.ssh/authorized_keys"
Nov 12 22:44:54.649408 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Nov 12 22:44:54.663959 systemd[1]: Starting sshkeys.service...
Nov 12 22:44:54.677255 systemd[1]: Finished sshkeys.service.
Nov 12 22:44:54.701143 sshd_keygen[1376]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Nov 12 22:44:54.727538 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Nov 12 22:44:54.737505 systemd[1]: Starting issuegen.service - Generate /run/issue...
Nov 12 22:44:54.743403 systemd[1]: issuegen.service: Deactivated successfully.
Nov 12 22:44:54.743604 systemd[1]: Finished issuegen.service - Generate /run/issue.
Nov 12 22:44:54.755608 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Nov 12 22:44:54.772016 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Nov 12 22:44:54.780745 systemd[1]: Started getty@tty1.service - Getty on tty1.
Nov 12 22:44:54.793705 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Nov 12 22:44:54.795165 systemd[1]: Reached target getty.target - Login Prompts.
Nov 12 22:44:54.851962 containerd[1377]: time="2024-11-12T22:44:54.851868525Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23
Nov 12 22:44:54.881633 containerd[1377]: time="2024-11-12T22:44:54.881532410Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.885032 containerd[1377]: time="2024-11-12T22:44:54.884973343Z" 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.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Nov 12 22:44:54.885120 containerd[1377]: time="2024-11-12T22:44:54.885103868Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Nov 12 22:44:54.885212 containerd[1377]: time="2024-11-12T22:44:54.885185922Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Nov 12 22:44:54.885524 containerd[1377]: time="2024-11-12T22:44:54.885475054Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Nov 12 22:44:54.885710 containerd[1377]: time="2024-11-12T22:44:54.885503127Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.885867 containerd[1377]: time="2024-11-12T22:44:54.885821283Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Nov 12 22:44:54.886034 containerd[1377]: time="2024-11-12T22:44:54.885946929Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.886400 containerd[1377]: time="2024-11-12T22:44:54.886283891Z" 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
Nov 12 22:44:54.886400 containerd[1377]: time="2024-11-12T22:44:54.886330488Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.886400 containerd[1377]: time="2024-11-12T22:44:54.886350416Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Nov 12 22:44:54.886400 containerd[1377]: time="2024-11-12T22:44:54.886364923Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.886713 containerd[1377]: time="2024-11-12T22:44:54.886638286Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.887624 containerd[1377]: time="2024-11-12T22:44:54.887500924Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Nov 12 22:44:54.888170 containerd[1377]: time="2024-11-12T22:44:54.888044122Z" 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
Nov 12 22:44:54.888407 containerd[1377]: time="2024-11-12T22:44:54.888170059Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Nov 12 22:44:54.889168 containerd[1377]: time="2024-11-12T22:44:54.889017438Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Nov 12 22:44:54.889996 containerd[1377]: time="2024-11-12T22:44:54.889894342Z" level=info msg="metadata content store policy set" policy=shared
Nov 12 22:44:54.978938 containerd[1377]: time="2024-11-12T22:44:54.978837245Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Nov 12 22:44:54.978938 containerd[1377]: time="2024-11-12T22:44:54.978975564Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Nov 12 22:44:54.979402 containerd[1377]: time="2024-11-12T22:44:54.979021911Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Nov 12 22:44:54.979402 containerd[1377]: time="2024-11-12T22:44:54.979066395Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Nov 12 22:44:54.980235 containerd[1377]: time="2024-11-12T22:44:54.979105508Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Nov 12 22:44:54.980235 containerd[1377]: time="2024-11-12T22:44:54.979900489Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Nov 12 22:44:54.980633 containerd[1377]: time="2024-11-12T22:44:54.980535330Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Nov 12 22:44:54.980910 containerd[1377]: time="2024-11-12T22:44:54.980821075Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Nov 12 22:44:54.980910 containerd[1377]: time="2024-11-12T22:44:54.980883272Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Nov 12 22:44:54.981042 containerd[1377]: time="2024-11-12T22:44:54.980926904Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Nov 12 22:44:54.981042 containerd[1377]: time="2024-11-12T22:44:54.980967530Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981042 containerd[1377]: time="2024-11-12T22:44:54.981003397Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981042 containerd[1377]: time="2024-11-12T22:44:54.981036830Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981294 containerd[1377]: time="2024-11-12T22:44:54.981073809Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981294 containerd[1377]: time="2024-11-12T22:44:54.981111240Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981294 containerd[1377]: time="2024-11-12T22:44:54.981147858Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981294 containerd[1377]: time="2024-11-12T22:44:54.981181872Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981294 containerd[1377]: time="2024-11-12T22:44:54.981255210Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981306135Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981346190Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981379633Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981417424Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981451788Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981486604Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981517572Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981550 containerd[1377]: time="2024-11-12T22:44:54.981550624Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981587272Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981637727Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981670990Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981705274Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981777960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981820670Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981870524Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981907453Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.981986 containerd[1377]: time="2024-11-12T22:44:54.981937149Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982038689Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982085026Z" 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
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982113489Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982145560Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982172751Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982270384Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982304959Z" level=info msg="NRI interface is disabled by configuration."
Nov 12 22:44:54.983093 containerd[1377]: time="2024-11-12T22:44:54.982335636Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Nov 12 22:44:54.984088 containerd[1377]: time="2024-11-12T22:44:54.983034497Z" 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}"
Nov 12 22:44:54.984088 containerd[1377]: time="2024-11-12T22:44:54.983165983Z" level=info msg="Connect containerd service"
Nov 12 22:44:54.984088 containerd[1377]: time="2024-11-12T22:44:54.983285908Z" level=info msg="using legacy CRI server"
Nov 12 22:44:54.984088 containerd[1377]: time="2024-11-12T22:44:54.983309192Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Nov 12 22:44:54.984088 containerd[1377]: time="2024-11-12T22:44:54.983523053Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.984806841Z" 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"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985125088Z" level=info msg="Start subscribing containerd event"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985318430Z" level=info msg="Start recovering state"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985421694Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985557078Z" level=info msg=serving... address=/run/containerd/containerd.sock
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985555184Z" level=info msg="Start event monitor"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985648620Z" level=info msg="Start snapshots syncer"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985674258Z" level=info msg="Start cni network conf syncer for default"
Nov 12 22:44:54.988337 containerd[1377]: time="2024-11-12T22:44:54.985692943Z" level=info msg="Start streaming server"
Nov 12 22:44:54.986097 systemd[1]: Started containerd.service - containerd container runtime.
Nov 12 22:44:54.993459 containerd[1377]: time="2024-11-12T22:44:54.992895922Z" level=info msg="containerd successfully booted in 0.141876s"
Nov 12 22:44:55.413391 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Nov 12 22:44:55.423814 systemd[1]: Started sshd@0-172.24.4.8:22-172.24.4.1:50898.service - OpenSSH per-connection server daemon (172.24.4.1:50898).
Nov 12 22:44:55.511590 systemd-networkd[1307]: eth0: Gained IPv6LL
Nov 12 22:44:55.514444 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:55.516042 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Nov 12 22:44:55.521314 systemd[1]: Reached target network-online.target - Network is Online.
Nov 12 22:44:55.538146 systemd[1]: Starting etcd-member.service - etcd (System Application Container)...
Nov 12 22:44:55.539875 (-wrapper)[1451]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS
Nov 12 22:44:55.553694 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Nov 12 22:44:55.633595 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Nov 12 22:44:55.800445 systemd[1]: Starting docker.service - Docker Application Container Engine...
Nov 12 22:44:55.804470 (dockerd)[1472]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU
Nov 12 22:44:56.350591 dockerd[1472]: time="2024-11-12T22:44:56.350474570Z" level=info msg="Starting up"
Nov 12 22:44:56.581086 systemd[1]: var-lib-docker-metacopy\x2dcheck1956928410-merged.mount: Deactivated successfully.
Nov 12 22:44:56.637923 dockerd[1472]: time="2024-11-12T22:44:56.637797134Z" level=info msg="Loading containers: start."
Nov 12 22:44:56.759970 sshd[1449]: Accepted publickey for core from 172.24.4.1 port 50898 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:44:56.764505 sshd-session[1449]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:44:56.788335 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Nov 12 22:44:56.804104 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Nov 12 22:44:56.815149 systemd-logind[1362]: New session 1 of user core.
Nov 12 22:44:56.831121 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Nov 12 22:44:56.839536 systemd[1]: Starting user@500.service - User Manager for UID 500...
Nov 12 22:44:56.846437 (systemd)[1557]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Nov 12 22:44:56.905229 kernel: Initializing XFRM netlink socket
Nov 12 22:44:56.940432 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:56.942736 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:56.949425 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:56.982150 systemd[1557]: Queued start job for default target default.target.
Nov 12 22:44:56.988187 systemd[1557]: Created slice app.slice - User Application Slice.
Nov 12 22:44:56.988625 systemd[1557]: Reached target paths.target - Paths.
Nov 12 22:44:56.988640 systemd[1557]: Reached target timers.target - Timers.
Nov 12 22:44:56.990351 systemd[1557]: Starting dbus.socket - D-Bus User Message Bus Socket...
Nov 12 22:44:56.999413 systemd-networkd[1307]: docker0: Link UP
Nov 12 22:44:56.999940 systemd-timesyncd[1265]: Network configuration changed, trying to establish connection.
Nov 12 22:44:57.006898 systemd[1557]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Nov 12 22:44:57.007030 systemd[1557]: Reached target sockets.target - Sockets.
Nov 12 22:44:57.007049 systemd[1557]: Reached target basic.target - Basic System.
Nov 12 22:44:57.007096 systemd[1557]: Reached target default.target - Main User Target.
Nov 12 22:44:57.007130 systemd[1557]: Startup finished in 151ms.
Nov 12 22:44:57.007357 systemd[1]: Started user@500.service - User Manager for UID 500.
Nov 12 22:44:57.014453 systemd[1]: Started session-1.scope - Session 1 of User core.
Nov 12 22:44:57.029435 dockerd[1472]: time="2024-11-12T22:44:57.029382772Z" level=info msg="Loading containers: done."
Nov 12 22:44:57.065704 dockerd[1472]: time="2024-11-12T22:44:57.065605518Z" 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
Nov 12 22:44:57.065879 dockerd[1472]: time="2024-11-12T22:44:57.065775186Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1
Nov 12 22:44:57.065933 dockerd[1472]: time="2024-11-12T22:44:57.065905451Z" level=info msg="Daemon has completed initialization"
Nov 12 22:44:57.185689 dockerd[1472]: time="2024-11-12T22:44:57.183944593Z" level=info msg="API listen on /run/docker.sock"
Nov 12 22:44:57.189603 systemd[1]: Started docker.service - Docker Application Container Engine.
Nov 12 22:44:57.190370 etcd-wrapper[1456]: Error response from daemon: No such container: etcd-member
Nov 12 22:44:57.228622 etcd-wrapper[1658]: Error response from daemon: No such container: etcd-member
Nov 12 22:44:57.306859 etcd-wrapper[1676]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally
Nov 12 22:44:57.428840 systemd[1]: Started sshd@1-172.24.4.8:22-172.24.4.1:50912.service - OpenSSH per-connection server daemon (172.24.4.1:50912).
Nov 12 22:44:57.530059 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1553319842-merged.mount: Deactivated successfully.
Nov 12 22:44:59.047355 sshd[1685]: Accepted publickey for core from 172.24.4.1 port 50912 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:44:59.051157 sshd-session[1685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:44:59.060821 systemd-logind[1362]: New session 2 of user core.
Nov 12 22:44:59.070479 systemd[1]: Started session-2.scope - Session 2 of User core.
Nov 12 22:44:59.338812 etcd-wrapper[1676]: v3.5.16: Pulling from coreos/etcd
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 804c8aba2cc6: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 2ae710cd8bfe: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: d462aa345367: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 0f8b424aa0b9: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: d557676654e5: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: c8022d07192e: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: d858cbc252ad: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 1069fc2daed1: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: b40161cd83fc: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 5318d93a3a65: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 307c1adadb60: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: fbb01d9e9dc9: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: fbfea02ac3cf: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 8c26e4bf18e2: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: 1e59a65f8816: Pulling fs layer
Nov 12 22:44:59.668134 etcd-wrapper[1676]: ffbd4ca5f0bd: Pulling fs layer
Nov 12 22:44:59.669616 etcd-wrapper[1676]: 0f8b424aa0b9: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: d557676654e5: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: c8022d07192e: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: d858cbc252ad: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: 1069fc2daed1: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: b40161cd83fc: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: 5318d93a3a65: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: 307c1adadb60: Waiting
Nov 12 22:44:59.669616 etcd-wrapper[1676]: fbb01d9e9dc9: Waiting
Nov 12 22:44:59.671389 etcd-wrapper[1676]: fbfea02ac3cf: Waiting
Nov 12 22:44:59.671389 etcd-wrapper[1676]: 8c26e4bf18e2: Waiting
Nov 12 22:44:59.671389 etcd-wrapper[1676]: 1e59a65f8816: Waiting
Nov 12 22:44:59.671389 etcd-wrapper[1676]: ffbd4ca5f0bd: Waiting
Nov 12 22:44:59.807667 sshd[1687]: Connection closed by 172.24.4.1 port 50912
Nov 12 22:44:59.809862 sshd-session[1685]: pam_unix(sshd:session): session closed for user core
Nov 12 22:44:59.824809 systemd[1]: sshd@1-172.24.4.8:22-172.24.4.1:50912.service: Deactivated successfully.
Nov 12 22:44:59.830457 systemd[1]: session-2.scope: Deactivated successfully.
Nov 12 22:44:59.838664 systemd-logind[1362]: Session 2 logged out. Waiting for processes to exit.
Nov 12 22:44:59.847880 systemd[1]: Started sshd@2-172.24.4.8:22-172.24.4.1:50926.service - OpenSSH per-connection server daemon (172.24.4.1:50926).
Nov 12 22:44:59.859344 systemd-logind[1362]: Removed session 2.
Nov 12 22:44:59.877057 login[1443]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Nov 12 22:44:59.877536 login[1444]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Nov 12 22:44:59.888392 systemd-logind[1362]: New session 4 of user core.
Nov 12 22:44:59.893510 systemd[1]: Started session-4.scope - Session 4 of User core.
Nov 12 22:44:59.896467 systemd-logind[1362]: New session 3 of user core.
Nov 12 22:44:59.905441 systemd[1]: Started session-3.scope - Session 3 of User core.
Nov 12 22:44:59.994548 etcd-wrapper[1676]: d462aa345367: Verifying Checksum
Nov 12 22:44:59.994548 etcd-wrapper[1676]: d462aa345367: Download complete
Nov 12 22:45:00.018912 etcd-wrapper[1676]: 804c8aba2cc6: Download complete
Nov 12 22:45:00.084102 etcd-wrapper[1676]: 804c8aba2cc6: Pull complete
Nov 12 22:45:00.305499 etcd-wrapper[1676]: 2ae710cd8bfe: Download complete
Nov 12 22:45:00.341698 systemd[1]: var-lib-docker-overlay2-fb4e54b62e4bc6bb978bc936eb1b2165badf12b0b953c81d0e8a2a760ca3d9e2-merged.mount: Deactivated successfully.
Nov 12 22:45:00.402594 etcd-wrapper[1676]: 2ae710cd8bfe: Pull complete
Nov 12 22:45:00.487187 etcd-wrapper[1676]: 0f8b424aa0b9: Verifying Checksum
Nov 12 22:45:00.487187 etcd-wrapper[1676]: 0f8b424aa0b9: Download complete
Nov 12 22:45:00.529017 etcd-wrapper[1676]: d557676654e5: Verifying Checksum
Nov 12 22:45:00.529017 etcd-wrapper[1676]: d557676654e5: Download complete
Nov 12 22:45:00.886754 etcd-wrapper[1676]: d858cbc252ad: Verifying Checksum
Nov 12 22:45:00.886754 etcd-wrapper[1676]: d858cbc252ad: Download complete
Nov 12 22:45:00.921476 etcd-wrapper[1676]: c8022d07192e: Verifying Checksum
Nov 12 22:45:00.921476 etcd-wrapper[1676]: c8022d07192e: Download complete
Nov 12 22:45:00.951116 systemd[1]: var-lib-docker-overlay2-1b577760f8256f8d5b9757decbb8775bb6b72ab382868e0b5d05907777f408d0-merged.mount: Deactivated successfully.
Nov 12 22:45:01.014391 etcd-wrapper[1676]: d462aa345367: Pull complete
Nov 12 22:45:01.047233 sshd[1694]: Accepted publickey for core from 172.24.4.1 port 50926 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:01.048339 sshd-session[1694]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:01.050188 etcd-wrapper[1676]: 0f8b424aa0b9: Pull complete
Nov 12 22:45:01.055281 systemd-logind[1362]: New session 5 of user core.
Nov 12 22:45:01.059733 systemd[1]: Started session-5.scope - Session 5 of User core.
Nov 12 22:45:01.083322 etcd-wrapper[1676]: d557676654e5: Pull complete
Nov 12 22:45:01.116720 etcd-wrapper[1676]: c8022d07192e: Pull complete
Nov 12 22:45:01.163246 etcd-wrapper[1676]: d858cbc252ad: Pull complete
Nov 12 22:45:01.322407 systemd[1]: var-lib-docker-overlay2-19a110dd353b0df39f48d1e6f82fc7b40aaffbc6552d9afdfa2b9924c1119ef1-merged.mount: Deactivated successfully.
Nov 12 22:45:01.625849 sshd[1746]: Connection closed by 172.24.4.1 port 50926
Nov 12 22:45:01.627471 sshd-session[1694]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:01.634084 systemd[1]: sshd@2-172.24.4.8:22-172.24.4.1:50926.service: Deactivated successfully.
Nov 12 22:45:01.640566 systemd[1]: session-5.scope: Deactivated successfully.
Nov 12 22:45:01.645652 systemd-logind[1362]: Session 5 logged out. Waiting for processes to exit.
Nov 12 22:45:01.648041 systemd-logind[1362]: Removed session 5.
Nov 12 22:45:01.918835 etcd-wrapper[1676]: b40161cd83fc: Verifying Checksum
Nov 12 22:45:01.918835 etcd-wrapper[1676]: b40161cd83fc: Download complete
Nov 12 22:45:02.143758 etcd-wrapper[1676]: 1069fc2daed1: Verifying Checksum
Nov 12 22:45:02.143758 etcd-wrapper[1676]: 1069fc2daed1: Download complete
Nov 12 22:45:02.180654 systemd[1]: var-lib-docker-overlay2-1d55a384fb4f2bfe5b7ca619dac52ad0219d87a2b46e5039415f5cdd4c9452bc-merged.mount: Deactivated successfully.
Nov 12 22:45:02.194785 etcd-wrapper[1676]: 1069fc2daed1: Pull complete
Nov 12 22:45:02.228936 systemd[1]: var-lib-docker-overlay2-977f3f01120ec64526692dc15c5d9d0bdb4d927a3b62327ab548cdef0feaa42a-merged.mount: Deactivated successfully.
Nov 12 22:45:02.247446 etcd-wrapper[1676]: b40161cd83fc: Pull complete
Nov 12 22:45:02.277434 etcd-wrapper[1676]: 5318d93a3a65: Verifying Checksum
Nov 12 22:45:02.277434 etcd-wrapper[1676]: 5318d93a3a65: Download complete
Nov 12 22:45:02.353834 etcd-wrapper[1676]: 5318d93a3a65: Pull complete
Nov 12 22:45:02.737258 etcd-wrapper[1676]: 307c1adadb60: Verifying Checksum
Nov 12 22:45:02.737258 etcd-wrapper[1676]: 307c1adadb60: Download complete
Nov 12 22:45:02.782852 systemd[1]: var-lib-docker-overlay2-4f71febffd969657a7833f768c7e7d3a3fec02b3f146bbfd469c6d5086aeeaee-merged.mount: Deactivated successfully.
Nov 12 22:45:02.828635 etcd-wrapper[1676]: 307c1adadb60: Pull complete
Nov 12 22:45:03.052846 etcd-wrapper[1676]: fbb01d9e9dc9: Verifying Checksum
Nov 12 22:45:03.052846 etcd-wrapper[1676]: fbb01d9e9dc9: Download complete
Nov 12 22:45:03.106253 etcd-wrapper[1676]: fbfea02ac3cf: Verifying Checksum
Nov 12 22:45:03.106253 etcd-wrapper[1676]: fbfea02ac3cf: Download complete
Nov 12 22:45:03.264566 etcd-wrapper[1676]: 8c26e4bf18e2: Verifying Checksum
Nov 12 22:45:03.264706 etcd-wrapper[1676]: 8c26e4bf18e2: Download complete
Nov 12 22:45:03.342386 systemd[1]: var-lib-docker-overlay2-dd8b9b3d2ed81b7e24e518bf217c57e24175d0922951c1caf7304cb0a6e1a283-merged.mount: Deactivated successfully.
Nov 12 22:45:03.593714 etcd-wrapper[1676]: fbb01d9e9dc9: Pull complete
Nov 12 22:45:03.687745 etcd-wrapper[1676]: 1e59a65f8816: Verifying Checksum
Nov 12 22:45:03.687745 etcd-wrapper[1676]: 1e59a65f8816: Download complete
Nov 12 22:45:03.842895 systemd[1]: var-lib-docker-overlay2-8e0ce543e30583b2e6457eb072a16f659fdb7e569a257df6f5399f87ef5c22a6-merged.mount: Deactivated successfully.
Nov 12 22:45:03.964859 etcd-wrapper[1676]: ffbd4ca5f0bd: Download complete
Nov 12 22:45:03.985416 etcd-wrapper[1676]: fbfea02ac3cf: Pull complete
Nov 12 22:45:04.194208 systemd[1]: var-lib-docker-overlay2-46624e7a202c1badeef3e59fd3086854a5074850f6898a86e05c1d1faddc23de-merged.mount: Deactivated successfully.
Nov 12 22:45:04.312658 etcd-wrapper[1676]: 8c26e4bf18e2: Pull complete
Nov 12 22:45:04.358337 etcd-wrapper[1676]: 1e59a65f8816: Pull complete
Nov 12 22:45:04.402581 systemd[1]: var-lib-docker-overlay2-9ea15fd13d5754f3631ee94a43e54ddf6b01363e060dcb77fb0698cb4ed574ce-merged.mount: Deactivated successfully.
Nov 12 22:45:04.439658 etcd-wrapper[1676]: ffbd4ca5f0bd: Pull complete
Nov 12 22:45:04.461947 etcd-wrapper[1676]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159
Nov 12 22:45:04.488614 etcd-wrapper[1676]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16
Nov 12 22:45:04.529033 systemd[1]: var-lib-docker-overlay2-62d5b1876c09a4a7a268a5b5da8f242eb6691013466878474f061752e0a93f1a\x2dinit-merged.mount: Deactivated successfully.
Nov 12 22:45:05.087582 containerd[1377]: time="2024-11-12T22:45:05.087263676Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Nov 12 22:45:05.088872 containerd[1377]: time="2024-11-12T22:45:05.087630714Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Nov 12 22:45:05.088872 containerd[1377]: time="2024-11-12T22:45:05.087749768Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Nov 12 22:45:05.088872 containerd[1377]: time="2024-11-12T22:45:05.088286905Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Nov 12 22:45:05.131563 systemd[1]: Started docker-f772183a4c00dfd5a73f0c64caffb3e0da60fe68e57ca839234d933c48f29e88.scope - libcontainer container f772183a4c00dfd5a73f0c64caffb3e0da60fe68e57ca839234d933c48f29e88.
Nov 12 22:45:05.230942 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.230256Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"}
Nov 12 22:45:05.230942 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.230403Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"c2cee9098b52441ca833f5dd5051dbef"}
Nov 12 22:45:05.230942 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.230433Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"}
Nov 12 22:45:05.230942 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.230441Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"}
Nov 12 22:45:05.232302 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.230448Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"}
Nov 12 22:45:05.232302 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.230454Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"}
Nov 12 22:45:05.232302 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.230500Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Nov 12 22:45:05.232302 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.230513Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://172.24.4.8:2379"]}
Nov 12 22:45:05.233255 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.231666Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."}
Nov 12 22:45:05.233255 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.231696Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]}
Nov 12 22:45:05.233255 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.232303Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]}
Nov 12 22:45:05.233255 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.232427Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"c2cee9098b52441ca833f5dd5051dbef","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,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.24.4.8:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"c2cee9098b52441ca833f5dd5051dbef=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
Nov 12 22:45:05.238865 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.238557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.810428ms"}
Nov 12 22:45:05.248555 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248088Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"}
Nov 12 22:45:05.248962 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"}
Nov 12 22:45:05.248962 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"}
Nov 12 22:45:05.248962 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
Nov 12 22:45:05.248962 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"}
Nov 12 22:45:05.248962 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.248404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Nov 12 22:45:05.255692 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:45:05.255191Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
Nov 12 22:45:05.258330 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.257819Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1}
Nov 12 22:45:05.260639 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.259985Z","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"}
Nov 12 22:45:05.262742 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.261506Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"}
Nov 12 22:45:05.262742 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.261963Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Nov 12 22:45:05.263766 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.263330Z","caller":"etcdserver/server.go:757","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"}
Nov 12 22:45:05.265832 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.264400Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
Nov 12 22:45:05.268650 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.268150Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
Nov 12 22:45:05.271173 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.270257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
Nov 12 22:45:05.271173 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.269945Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"}
Nov 12 22:45:05.271173 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.270438Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"}
Nov 12 22:45:05.271173 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.264904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"}
Nov 12 22:45:05.271173 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.270635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]}
Nov 12 22:45:05.272034 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.270159Z","caller":"embed/etcd.go:280","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://172.24.4.8:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]}
Nov 12 22:45:05.351362 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"}
Nov 12 22:45:05.351362 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.349871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.350957Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:c2cee9098b52441ca833f5dd5051dbef ClientURLs:[http://172.24.4.8:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.351008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.351591Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
Nov 12 22:45:05.356497 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.355850Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"}
Nov 12 22:45:05.359394 systemd[1]: Started etcd-member.service - etcd (System Application Container).
Nov 12 22:45:05.360126 systemd[1]: Reached target multi-user.target - Multi-User System.
Nov 12 22:45:05.361509 systemd[1]: Startup finished in 1.136s (kernel) + 11.977s (initrd) + 14.664s (userspace) = 27.778s.
Nov 12 22:45:05.362312 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.358304Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
Nov 12 22:45:05.362312 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.356461Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"}
Nov 12 22:45:05.362312 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.356673Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
Nov 12 22:45:05.362312 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.359292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
Nov 12 22:45:05.362312 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.359442Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"}
Nov 12 22:45:05.363479 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:45:05.363110Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"}
Nov 12 22:45:11.638508 systemd[1]: Started sshd@3-172.24.4.8:22-172.24.4.1:44696.service - OpenSSH per-connection server daemon (172.24.4.1:44696).
Nov 12 22:45:13.040772 sshd[1855]: Accepted publickey for core from 172.24.4.1 port 44696 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:13.043444 sshd-session[1855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:13.055823 systemd-logind[1362]: New session 6 of user core.
Nov 12 22:45:13.068587 systemd[1]: Started session-6.scope - Session 6 of User core.
Nov 12 22:45:13.697654 sshd[1857]: Connection closed by 172.24.4.1 port 44696
Nov 12 22:45:13.698314 sshd-session[1855]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:13.712962 systemd[1]: sshd@3-172.24.4.8:22-172.24.4.1:44696.service: Deactivated successfully.
Nov 12 22:45:13.717372 systemd[1]: session-6.scope: Deactivated successfully.
Nov 12 22:45:13.719390 systemd-logind[1362]: Session 6 logged out. Waiting for processes to exit.
Nov 12 22:45:13.727811 systemd[1]: Started sshd@4-172.24.4.8:22-172.24.4.1:51456.service - OpenSSH per-connection server daemon (172.24.4.1:51456).
Nov 12 22:45:13.731620 systemd-logind[1362]: Removed session 6.
Nov 12 22:45:15.190658 sshd[1862]: Accepted publickey for core from 172.24.4.1 port 51456 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:15.194160 sshd-session[1862]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:15.206395 systemd-logind[1362]: New session 7 of user core.
Nov 12 22:45:15.215514 systemd[1]: Started session-7.scope - Session 7 of User core.
Nov 12 22:45:16.089275 sshd[1864]: Connection closed by 172.24.4.1 port 51456
Nov 12 22:45:16.090540 sshd-session[1862]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:16.099610 systemd[1]: sshd@4-172.24.4.8:22-172.24.4.1:51456.service: Deactivated successfully.
Nov 12 22:45:16.102464 systemd[1]: session-7.scope: Deactivated successfully.
Nov 12 22:45:16.104061 systemd-logind[1362]: Session 7 logged out. Waiting for processes to exit.
Nov 12 22:45:16.111776 systemd[1]: Started sshd@5-172.24.4.8:22-172.24.4.1:51464.service - OpenSSH per-connection server daemon (172.24.4.1:51464).
Nov 12 22:45:16.114388 systemd-logind[1362]: Removed session 7.
Nov 12 22:45:17.697067 sshd[1869]: Accepted publickey for core from 172.24.4.1 port 51464 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:17.699921 sshd-session[1869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:17.709509 systemd-logind[1362]: New session 8 of user core.
Nov 12 22:45:17.719693 systemd[1]: Started session-8.scope - Session 8 of User core.
Nov 12 22:45:18.294250 sshd[1871]: Connection closed by 172.24.4.1 port 51464
Nov 12 22:45:18.294706 sshd-session[1869]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:18.307118 systemd[1]: sshd@5-172.24.4.8:22-172.24.4.1:51464.service: Deactivated successfully.
Nov 12 22:45:18.310876 systemd[1]: session-8.scope: Deactivated successfully.
Nov 12 22:45:18.312500 systemd-logind[1362]: Session 8 logged out. Waiting for processes to exit.
Nov 12 22:45:18.320822 systemd[1]: Started sshd@6-172.24.4.8:22-172.24.4.1:51480.service - OpenSSH per-connection server daemon (172.24.4.1:51480).
Nov 12 22:45:18.323526 systemd-logind[1362]: Removed session 8.
Nov 12 22:45:19.810481 sshd[1876]: Accepted publickey for core from 172.24.4.1 port 51480 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:19.813189 sshd-session[1876]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:19.822621 systemd-logind[1362]: New session 9 of user core.
Nov 12 22:45:19.831578 systemd[1]: Started session-9.scope - Session 9 of User core.
Nov 12 22:45:20.468670 sshd[1878]: Connection closed by 172.24.4.1 port 51480
Nov 12 22:45:20.469412 sshd-session[1876]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:20.478838 systemd[1]: sshd@6-172.24.4.8:22-172.24.4.1:51480.service: Deactivated successfully.
Nov 12 22:45:20.482149 systemd[1]: session-9.scope: Deactivated successfully.
Nov 12 22:45:20.483978 systemd-logind[1362]: Session 9 logged out. Waiting for processes to exit.
Nov 12 22:45:20.492763 systemd[1]: Started sshd@7-172.24.4.8:22-172.24.4.1:51482.service - OpenSSH per-connection server daemon (172.24.4.1:51482).
Nov 12 22:45:20.495933 systemd-logind[1362]: Removed session 9.
Nov 12 22:45:21.794264 sshd[1885]: Accepted publickey for core from 172.24.4.1 port 51482 ssh2: RSA SHA256:ktk6veDlhGrBWheXoxrCvRhk8b8SzDWdEn7dUGabdWk
Nov 12 22:45:21.797163 sshd-session[1885]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Nov 12 22:45:21.806526 systemd-logind[1362]: New session 10 of user core.
Nov 12 22:45:21.819557 systemd[1]: Started session-10.scope - Session 10 of User core.
Nov 12 22:45:22.760641 sshd[1887]: Connection closed by 172.24.4.1 port 51482
Nov 12 22:45:22.760412 sshd-session[1885]: pam_unix(sshd:session): session closed for user core
Nov 12 22:45:22.769581 systemd-logind[1362]: Session 10 logged out. Waiting for processes to exit.
Nov 12 22:45:22.770689 systemd[1]: sshd@7-172.24.4.8:22-172.24.4.1:51482.service: Deactivated successfully.
Nov 12 22:45:22.774029 systemd[1]: session-10.scope: Deactivated successfully.
Nov 12 22:45:22.778827 systemd-logind[1362]: Removed session 10.
Nov 12 22:45:27.943499 systemd-timesyncd[1265]: Contacted time server 162.159.200.123:123 (2.flatcar.pool.ntp.org).
Nov 12 22:45:27.943574 systemd-resolved[1244]: Clock change detected. Flushing caches.
Nov 12 22:45:27.943611 systemd-timesyncd[1265]: Initial clock synchronization to Tue 2024-11-12 22:45:27.943044 UTC.
Nov 12 22:45:40.445464 update_engine[1365]: I20241112 22:45:40.444774  1365 update_attempter.cc:509] Updating boot flags...
Nov 12 22:45:40.491380 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1904)
Nov 12 22:45:40.561649 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1907)
Nov 12 22:46:56.665371 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:46:56.662603Z","caller":"traceutil/trace.go:171","msg":"trace[1812791098] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"107.478771ms","start":"2024-11-12T22:46:56.555077Z","end":"2024-11-12T22:46:56.662555Z","steps":["trace[1812791098] 'process raft request'  (duration: 45.029832ms)","trace[1812791098] 'compare'  (duration: 62.25775ms)"],"step_count":2}
Nov 12 22:47:09.500872 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:47:09.499289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.839305ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587882723797787501 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/coredns-6f6b679f8f-xtb7v.18075a154e7f6eba\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-6f6b679f8f-xtb7v.18075a154e7f6eba\" value_size:657 lease:7587882723797787152 >> failure:<>>","response":"size:16"}
Nov 12 22:47:09.500872 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:09.499720Z","caller":"traceutil/trace.go:171","msg":"trace[1837778660] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"152.10475ms","start":"2024-11-12T22:47:09.347554Z","end":"2024-11-12T22:47:09.499659Z","steps":["trace[1837778660] 'process raft request'  (duration: 151.899897ms)"],"step_count":1}
Nov 12 22:47:09.500872 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:09.499822Z","caller":"traceutil/trace.go:171","msg":"trace[1293663970] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"191.132846ms","start":"2024-11-12T22:47:09.308664Z","end":"2024-11-12T22:47:09.499797Z","steps":["trace[1293663970] 'process raft request'  (duration: 11.237444ms)","trace[1293663970] 'compare'  (duration: 178.710112ms)"],"step_count":2}
Nov 12 22:47:10.566249 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:10.565723Z","caller":"traceutil/trace.go:171","msg":"trace[1214985235] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"186.515222ms","start":"2024-11-12T22:47:10.379179Z","end":"2024-11-12T22:47:10.565694Z","steps":["trace[1214985235] 'process raft request'  (duration: 164.532364ms)","trace[1214985235] 'compare'  (duration: 21.697173ms)"],"step_count":2}
Nov 12 22:47:21.674562 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:21.672708Z","caller":"traceutil/trace.go:171","msg":"trace[1139095700] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"132.651525ms","start":"2024-11-12T22:47:21.539969Z","end":"2024-11-12T22:47:21.672621Z","steps":["trace[1139095700] 'process raft request'  (duration: 132.573388ms)"],"step_count":1}
Nov 12 22:47:21.674562 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:21.672940Z","caller":"traceutil/trace.go:171","msg":"trace[751609677] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"171.338072ms","start":"2024-11-12T22:47:21.501580Z","end":"2024-11-12T22:47:21.672918Z","steps":["trace[751609677] 'process raft request'  (duration: 170.860717ms)"],"step_count":1}
Nov 12 22:47:21.674562 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:47:21.672983Z","caller":"traceutil/trace.go:171","msg":"trace[58663048] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"172.816751ms","start":"2024-11-12T22:47:21.500148Z","end":"2024-11-12T22:47:21.672965Z","steps":["trace[58663048] 'process raft request'  (duration: 172.054604ms)"],"step_count":1}
Nov 12 22:48:01.607946 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.606394Z","caller":"traceutil/trace.go:171","msg":"trace[507804801] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"118.281077ms","start":"2024-11-12T22:48:01.488092Z","end":"2024-11-12T22:48:01.606373Z","steps":["trace[507804801] 'read index received'  (duration: 118.107802ms)","trace[507804801] 'applied index is now lower than readState.Index'  (duration: 172.614µs)"],"step_count":2}
Nov 12 22:48:01.607946 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.606492Z","caller":"traceutil/trace.go:171","msg":"trace[278946122] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"120.250801ms","start":"2024-11-12T22:48:01.486232Z","end":"2024-11-12T22:48:01.606483Z","steps":["trace[278946122] 'process raft request'  (duration: 120.021551ms)"],"step_count":1}
Nov 12 22:48:01.607946 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:01.606667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.516579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/172.24.4.94.18075a215dade914\" ","response":"range_response_count:1 size:678"}
Nov 12 22:48:01.607946 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.606695Z","caller":"traceutil/trace.go:171","msg":"trace[114425364] range","detail":"{range_begin:/registry/events/default/172.24.4.94.18075a215dade914; range_end:; response_count:1; response_revision:500; }","duration":"118.632987ms","start":"2024-11-12T22:48:01.488054Z","end":"2024-11-12T22:48:01.606687Z","steps":["trace[114425364] 'agreement among raft nodes before linearized reading'  (duration: 118.50653ms)"],"step_count":1}
Nov 12 22:48:01.879478 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.877986Z","caller":"traceutil/trace.go:171","msg":"trace[1817190798] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"182.344734ms","start":"2024-11-12T22:48:01.695613Z","end":"2024-11-12T22:48:01.877958Z","steps":["trace[1817190798] 'read index received'  (duration: 33.641816ms)","trace[1817190798] 'applied index is now lower than readState.Index'  (duration: 148.701907ms)"],"step_count":2}
Nov 12 22:48:01.879478 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.878074Z","caller":"traceutil/trace.go:171","msg":"trace[686484129] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"185.578147ms","start":"2024-11-12T22:48:01.692435Z","end":"2024-11-12T22:48:01.878013Z","steps":["trace[686484129] 'process raft request'  (duration: 122.675726ms)","trace[686484129] 'compare'  (duration: 62.643977ms)"],"step_count":2}
Nov 12 22:48:01.879478 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.878132Z","caller":"traceutil/trace.go:171","msg":"trace[834021737] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"185.513696ms","start":"2024-11-12T22:48:01.692603Z","end":"2024-11-12T22:48:01.878117Z","steps":["trace[834021737] 'process raft request'  (duration: 185.26561ms)"],"step_count":1}
Nov 12 22:48:01.879478 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:01.878308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.672169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"}
Nov 12 22:48:01.879478 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.878404Z","caller":"traceutil/trace.go:171","msg":"trace[93085065] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:504; }","duration":"182.798637ms","start":"2024-11-12T22:48:01.695590Z","end":"2024-11-12T22:48:01.878389Z","steps":["trace[93085065] 'agreement among raft nodes before linearized reading'  (duration: 182.648826ms)"],"step_count":1}
Nov 12 22:48:01.880026 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:01.878499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.213119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas\" limit:1 ","response":"range_response_count:0 size:5"}
Nov 12 22:48:01.880026 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:01.878556Z","caller":"traceutil/trace.go:171","msg":"trace[738752593] range","detail":"{range_begin:/registry/resourcequotas; range_end:; response_count:0; response_revision:504; }","duration":"182.304441ms","start":"2024-11-12T22:48:01.696232Z","end":"2024-11-12T22:48:01.878537Z","steps":["trace[738752593] 'agreement among raft nodes before linearized reading'  (duration: 182.182171ms)"],"step_count":1}
Nov 12 22:48:02.064684 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:02.063901Z","caller":"traceutil/trace.go:171","msg":"trace[1456660862] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"109.870993ms","start":"2024-11-12T22:48:01.953992Z","end":"2024-11-12T22:48:02.063863Z","steps":["trace[1456660862] 'process raft request'  (duration: 32.431188ms)","trace[1456660862] 'compare'  (duration: 77.299111ms)"],"step_count":2}
Nov 12 22:48:02.067740 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:02.066941Z","caller":"traceutil/trace.go:171","msg":"trace[1677344237] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"106.909001ms","start":"2024-11-12T22:48:01.960011Z","end":"2024-11-12T22:48:02.066920Z","steps":["trace[1677344237] 'process raft request'  (duration: 106.399695ms)"],"step_count":1}
Nov 12 22:48:02.067740 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:02.066937Z","caller":"traceutil/trace.go:171","msg":"trace[365134099] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"106.593419ms","start":"2024-11-12T22:48:01.960277Z","end":"2024-11-12T22:48:02.066871Z","steps":["trace[365134099] 'process raft request'  (duration: 106.291894ms)"],"step_count":1}
Nov 12 22:48:02.067740 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:02.067008Z","caller":"traceutil/trace.go:171","msg":"trace[935701731] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"103.708691ms","start":"2024-11-12T22:48:01.963287Z","end":"2024-11-12T22:48:02.066995Z","steps":["trace[935701731] 'process raft request'  (duration: 103.346954ms)"],"step_count":1}
Nov 12 22:48:03.467658 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:03.466898Z","caller":"traceutil/trace.go:171","msg":"trace[1773410514] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"128.953495ms","start":"2024-11-12T22:48:03.337897Z","end":"2024-11-12T22:48:03.466850Z","steps":["trace[1773410514] 'process raft request'  (duration: 128.781061ms)"],"step_count":1}
Nov 12 22:48:14.602678 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:14.598369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.07847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
Nov 12 22:48:14.602678 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:14.598499Z","caller":"traceutil/trace.go:171","msg":"trace[1898111957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"119.313582ms","start":"2024-11-12T22:48:14.479162Z","end":"2024-11-12T22:48:14.598476Z","steps":["trace[1898111957] 'range keys from in-memory index tree'  (duration: 118.949679ms)"],"step_count":1}
Nov 12 22:48:34.266649 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:34.264429Z","caller":"traceutil/trace.go:171","msg":"trace[178520928] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"121.215747ms","start":"2024-11-12T22:48:34.143158Z","end":"2024-11-12T22:48:34.264374Z","steps":["trace[178520928] 'process raft request'  (duration: 120.840283ms)"],"step_count":1}
Nov 12 22:48:34.691191 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:34.689898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
Nov 12 22:48:34.691191 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:34.690024Z","caller":"traceutil/trace.go:171","msg":"trace[534816441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"208.226551ms","start":"2024-11-12T22:48:34.481767Z","end":"2024-11-12T22:48:34.689993Z","steps":["trace[534816441] 'range keys from in-memory index tree'  (duration: 207.14881ms)"],"step_count":1}
Nov 12 22:48:40.591924 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:40.590551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.265601ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7587882723797788068 > lease_revoke:<id:694d93228d4a6539>","response":"size:29"}
Nov 12 22:48:40.593807 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.590881Z","caller":"traceutil/trace.go:171","msg":"trace[1951550826] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"105.954357ms","start":"2024-11-12T22:48:40.484901Z","end":"2024-11-12T22:48:40.590856Z","steps":["trace[1951550826] 'process raft request'  (duration: 105.803814ms)"],"step_count":1}
Nov 12 22:48:40.593807 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.591065Z","caller":"traceutil/trace.go:171","msg":"trace[787174044] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"110.263046ms","start":"2024-11-12T22:48:40.480724Z","end":"2024-11-12T22:48:40.590987Z","steps":["trace[787174044] 'read index received'  (duration: 73.919µs)","trace[787174044] 'applied index is now lower than readState.Index'  (duration: 110.185099ms)"],"step_count":2}
Nov 12 22:48:40.593807 etcd-wrapper[1676]: {"level":"warn","ts":"2024-11-12T22:48:40.591232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.483909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
Nov 12 22:48:40.593807 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.591385Z","caller":"traceutil/trace.go:171","msg":"trace[563826606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"110.647205ms","start":"2024-11-12T22:48:40.480714Z","end":"2024-11-12T22:48:40.591361Z","steps":["trace[563826606] 'agreement among raft nodes before linearized reading'  (duration: 110.450757ms)"],"step_count":1}
Nov 12 22:48:40.872673 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.870872Z","caller":"traceutil/trace.go:171","msg":"trace[745681740] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"151.03385ms","start":"2024-11-12T22:48:40.719806Z","end":"2024-11-12T22:48:40.870840Z","steps":["trace[745681740] 'process raft request'  (duration: 129.034099ms)","trace[745681740] 'compare'  (duration: 21.197466ms)"],"step_count":2}
Nov 12 22:48:40.896496 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.895735Z","caller":"traceutil/trace.go:171","msg":"trace[341754183] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"174.656813ms","start":"2024-11-12T22:48:40.721041Z","end":"2024-11-12T22:48:40.895698Z","steps":["trace[341754183] 'process raft request'  (duration: 174.347784ms)"],"step_count":1}
Nov 12 22:48:40.897119 etcd-wrapper[1676]: {"level":"info","ts":"2024-11-12T22:48:40.895741Z","caller":"traceutil/trace.go:171","msg":"trace[1509072123] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"173.415355ms","start":"2024-11-12T22:48:40.722289Z","end":"2024-11-12T22:48:40.895704Z","steps":["trace[1509072123] 'process raft request'  (duration: 173.324845ms)"],"step_count":1}