[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-10.fc44 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 429108035 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002385] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.009124] LSM: Security Framework initializing [ 0.010047] Yama: becoming mindful. [ 0.011032] SELinux: Initializing. [ 0.012071] *** VALIDATE selinux *** [ 0.020049] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025445] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026135] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027104] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028113] *** VALIDATE tmpfs *** [ 0.029464] *** VALIDATE proc *** [ 0.030215] *** VALIDATE cgroup *** [ 0.031008] *** VALIDATE cgroup2 *** [ 0.033237] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034164] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036030] Spectre V2 : User space: Vulnerable [ 0.037007] Speculative Store Bypass: Vulnerable [ 0.040530] debug: unmapping init [mem 0xffffffff8e859000-0xffffffff8e860fff] [ 0.043175] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044680] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045025] ... version: 2 [ 0.046014] ... bit width: 48 [ 0.047015] ... generic registers: 4 [ 0.048011] ... value mask: 0000ffffffffffff [ 0.049015] ... max period: 00007fffffffffff [ 0.050015] ... fixed-purpose events: 3 [ 0.051012] ... event mask: 000000070000000f [ 0.052302] rcu: Hierarchical SRCU implementation. [ 0.054400] smp: Bringing up secondary CPUs ... [ 0.055542] x86: Booting SMP configuration: [ 0.056024] .... node #0, CPUs: #1 #2 #3 [ 0.061040] smp: Brought up 1 node, 4 CPUs [ 0.063021] smpboot: Max logical packages: 1 [ 0.064009] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.135021] node 0 deferred pages initialised in 69ms [ 0.138384] devtmpfs: initialized [ 0.140365] x86/mm: Memory block size: 128MB [ 0.143221] gcov: version magic: 0x41383552 [ 0.145181] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.148102] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150370] pinctrl core: initialized pinctrl subsystem [ 0.153228] [ 0.153838] ************************************************************* [ 0.156014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158013] ** ** [ 0.161017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.164013] ** ** [ 0.166010] ** This means that this kernel is built to expose internal ** [ 0.168011] ** IOMMU data structures, which may compromise security on ** [ 0.170010] ** your system. ** [ 0.172013] ** ** [ 0.174013] ** If you see this message and you are not debugging the ** [ 0.177016] ** kernel, report this immediately to your vendor! ** [ 0.180015] ** ** [ 0.182009] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.184014] ************************************************************* [ 0.186660] NET: Registered protocol family 16 [ 0.189432] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.191055] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.193064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.197004] cpuidle: using governor menu [ 0.199242] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.201562] PCI: Using configuration type 1 for base access [ 0.205126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.213081] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.214041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.216052] cryptd: max_cpu_qlen set to 1000 [ 0.219378] ACPI: Added _OSI(Module Device) [ 0.221022] ACPI: Added _OSI(Processor Device) [ 0.222023] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.224023] ACPI: Added _OSI(Processor Aggregator Device) [ 0.229310] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.238223] ACPI: Interpreter enabled [ 0.240067] ACPI: PM: (supports S0 S3 S4 S5) [ 0.242013] ACPI: Using IOAPIC for interrupt routing [ 0.244119] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.248631] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.261262] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.264041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.266019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.268097] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.273297] acpiphp: Slot [2] registered [ 0.275127] acpiphp: Slot [5] registered [ 0.276087] acpiphp: Slot [6] registered [ 0.278142] acpiphp: Slot [3] registered [ 0.279088] acpiphp: Slot [4] registered [ 0.281107] acpiphp: Slot [7] registered [ 0.282107] acpiphp: Slot [8] registered [ 0.284102] acpiphp: Slot [9] registered [ 0.285158] acpiphp: Slot [10] registered [ 0.287105] acpiphp: Slot [11] registered [ 0.289103] acpiphp: Slot [12] registered [ 0.290104] acpiphp: Slot [13] registered [ 0.292103] acpiphp: Slot [14] registered [ 0.293087] acpiphp: Slot [15] registered [ 0.295127] acpiphp: Slot [16] registered [ 0.297103] acpiphp: Slot [17] registered [ 0.298120] acpiphp: Slot [18] registered [ 0.300140] acpiphp: Slot [19] registered [ 0.302072] acpiphp: Slot [20] registered [ 0.303082] acpiphp: Slot [21] registered [ 0.304105] acpiphp: Slot [22] registered [ 0.306126] acpiphp: Slot [23] registered [ 0.307081] acpiphp: Slot [24] registered [ 0.308085] acpiphp: Slot [25] registered [ 0.309118] acpiphp: Slot [26] registered [ 0.310040] acpiphp: Slot [27] registered [ 0.312113] acpiphp: Slot [28] registered [ 0.313101] acpiphp: Slot [29] registered [ 0.315115] acpiphp: Slot [30] registered [ 0.316122] acpiphp: Slot [31] registered [ 0.317061] PCI host bridge to bus 0000:00 [ 0.319031] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.321019] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.323022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.325024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.327031] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.330023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.332188] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.335112] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.338388] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.347617] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.352604] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.355018] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.358020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.360018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.363657] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.367114] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.370043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373861] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.379017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.388713] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.395014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.400527] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.408016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.415017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.430027] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.442587] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.450019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.455015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.471022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.484085] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.487590] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.492231] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.496507] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.499280] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.504656] iommu: Default domain type: Passthrough [ 0.506551] SCSI subsystem initialized [ 0.508240] ACPI: bus type USB registered [ 0.510217] usbcore: registered new interface driver usbfs [ 0.513128] usbcore: registered new interface driver hub [ 0.515145] usbcore: registered new device driver usb [ 0.518243] pps_core: LinuxPPS API ver. 1 registered [ 0.520073] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.524089] PTP clock support registered [ 0.527160] EDAC MC: Ver: 3.0.0 [ 0.529161] PCI: Using ACPI for IRQ routing [ 0.532144] NetLabel: Initializing [ 0.533015] NetLabel: domain hash size = 128 [ 0.535019] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.538167] NetLabel: unlabeled traffic allowed by default [ 0.541335] vgaarb: loaded [ 0.543760] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.546088] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.558000] clocksource: Switched to clocksource kvm-clock [ 0.671901] VFS: Disk quotas dquot_6.6.0 [ 0.673589] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.676042] *** VALIDATE ramfs *** [ 0.677228] *** VALIDATE hugetlbfs *** [ 0.679524] pnp: PnP ACPI init [ 0.681559] pnp: PnP ACPI: found 6 devices [ 0.700818] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.704811] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.707310] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.709847] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.713037] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.715788] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.719356] NET: Registered protocol family 2 [ 0.722119] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.727749] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.732284] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.739172] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.743832] TCP: Hash tables configured (established 65536 bind 65536) [ 0.746953] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.750958] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.754469] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.757872] NET: Registered protocol family 1 [ 0.760626] RPC: Registered named UNIX socket transport module. [ 0.762920] RPC: Registered udp transport module. [ 0.764598] RPC: Registered tcp transport module. [ 0.766592] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.769844] NET: Registered protocol family 44 [ 0.771861] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.774269] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.776627] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.778889] PCI: CLS 0 bytes, default 64 [ 0.780765] Unpacking initramfs... [ 2.223360] debug: unmapping init [mem 0xffff8963bcc64000-0xffff8963bffcffff] [ 2.229483] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.231851] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.237338] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.734037] Initialise system trusted keyrings [ 2.737023] Key type blacklist registered [ 2.739310] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.747534] zbud: loaded [ 2.751250] *** VALIDATE nfs *** [ 2.752514] *** VALIDATE nfs4 *** [ 2.753705] pstore: using deflate compression [ 2.757674] Platform Keyring initialized [ 2.855359] NET: Registered protocol family 38 [ 2.857027] Key type asymmetric registered [ 2.859177] Asymmetric key parser 'x509' registered [ 2.861152] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.864667] io scheduler mq-deadline registered [ 2.866889] io scheduler kyber registered [ 2.868855] io scheduler bfq registered [ 2.871028] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.874330] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.878123] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.881460] ACPI: Power Button [PWRF] [ 2.886226] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.892980] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.904041] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.932284] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.961609] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.967579] Non-volatile memory driver v1.3 [ 2.969572] Linux agpgart interface v0.103 [ 3.004137] virtio_blk virtio1: [vda] 150608 512-byte logical blocks (77.1 MB/73.5 MiB) [ 3.007480] vda: detected capacity change from 0 to 77111296 [ 3.028022] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.031642] vdb: detected capacity change from 0 to 1073741824 [ 3.039938] libphy: Fixed MDIO Bus: probed [ 3.049503] usbcore: registered new interface driver usbserial_generic [ 3.051568] usbserial: USB Serial support registered for generic [ 3.053362] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.056526] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.057960] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.060039] mousedev: PS/2 mouse device common for all mice [ 3.063578] rtc_cmos 00:05: RTC can wake from S4 [ 3.066332] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.066595] rtc_cmos 00:05: registered as rtc0 [ 3.070893] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.073697] intel_pstate: CPU model not supported [ 3.076569] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.080346] hid: raw HID events driver (C) Jiri Kosina [ 3.082839] usbcore: registered new interface driver usbhid [ 3.084754] usbhid: USB HID core driver [ 3.086756] drop_monitor: Initializing network drop monitor service [ 3.086801] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.089376] Initializing XFRM netlink socket [ 3.094676] NET: Registered protocol family 10 [ 3.097568] Segment Routing with IPv6 [ 3.099053] NET: Registered protocol family 17 [ 3.101387] mpls_gso: MPLS GSO support [ 3.105962] RAS: Correctable Errors collector initialized. [ 3.107961] AVX version of gcm_enc/dec engaged. [ 3.109713] AES CTR mode by8 optimization enabled [ 3.187985] sched_clock: Marking stable (3187956686, 0)->(4082137510, -894180824) [ 3.191903] registered taskstats version 1 [ 3.194179] Loading compiled-in X.509 certificates [ 3.196129] zswap: loaded using pool lzo/zbud [ 3.225195] Key type big_key registered [ 3.240678] Key type encrypted registered [ 3.242364] ima: No TPM chip found, activating TPM-bypass! [ 3.244705] ima: Allocated hash algorithm: sha1 [ 3.246175] ima: No architecture policies found [ 3.248580] evm: Initialising EVM extended attributes: [ 3.250015] evm: security.selinux [ 3.251049] evm: security.ima [ 3.251917] evm: security.capability [ 3.253184] evm: HMAC attrs: 0x1 [ 3.255615] rtc_cmos 00:05: setting system clock to 2026-09-29 19:35:27 UTC (1790710527) [ 3.261558] debug: unmapping init [mem 0xffffffff8f803000-0xffffffff8f9fffff] [ 3.264189] debug: unmapping init [mem 0xffffffff8e582000-0xffffffff8e858fff] [ 3.274349] Write protecting the kernel read-only data: 28672k [ 3.277533] debug: unmapping init [mem 0xffffffff8cc03000-0xffffffff8cdfffff] [ 3.280098] debug: unmapping init [mem 0xffffffff8d514000-0xffffffff8d5fffff] [ 3.313372] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.322626] systemd[1]: Detected virtualization kvm. [ 3.324370] systemd[1]: Detected architecture x86-64. [ 3.326332] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.352428] systemd[1]: No hostname configured. [ 3.354552] systemd[1]: Set hostname to . [ 3.356914] random: systemd: uninitialized urandom read (16 bytes read) [ 3.359762] systemd[1]: Initializing machine ID from random generator. [ 3.404806] random: ln: uninitialized urandom read (6 bytes read) [ 3.512456] random: systemd: uninitialized urandom read (16 bytes read) [ 3.515807] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.520641] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.525759] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.161659] device-mapper: uevent: version 1.0.3 [ 4.164155] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. [ 4.540493] random: fast init done Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.915077] virtio_net virtio0 ens2: renamed from eth0 [ 4.994851] scsi host0: ata_piix [ 5.076477] scsi host1: ata_piix [ 5.078710] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.081294] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.699963] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.758684] random: crng init done [ 9.759691] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 10.274956] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.506801] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.796389] SELinux: Disabled at runtime. [ 11.860071] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.869499] systemd[1]: Detected virtualization kvm. [ 11.871558] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.442717] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.446462] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.452479] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.458283] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.461062] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.468380] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.471888] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. Mounting Huge Pages File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ 12.562585] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.912268] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.166391] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.208777] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.323216] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.344395] EDAC sbridge: Ver: 1.1.2 [ 14.719354] Key type dns_resolver registered [ 15.038543] NFS: Registering the id_resolver key type [ 15.040766] Key type id_resolver registered [ 15.042562] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg658-client login: [ 44.798133] libcfs: loading out-of-tree module taints kernel. [ 44.844641] Key type ._llcrypt registered [ 44.846400] Key type .llcrypt registered [ 45.200969] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.209443] alg: No test for adler32 (adler32-zlib) [ 46.261642] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 46.652106] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 48.295206] Key type lgssc registered [ 49.065405] Lustre: Echo OBD driver; http://www.lustre.org/ [ 156.358128] hrtimer: interrupt took 4236089 ns [ 200.035668] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 204.858909] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 220.503805] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing check_logdir /tmp/testlogs/ [ 225.760050] Lustre: lustre-OST0000-osc-ffff89641170d800: disconnect after 24s idle [ 225.954548] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing yml_node [ 230.009726] Lustre: DEBUG MARKER: Client: 2.17.59.2 [ 232.366784] Lustre: DEBUG MARKER: MDS: 2.17.59.2 [ 234.641962] Lustre: DEBUG MARKER: OSS: 2.17.59.2 [ 235.985491] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Sep 29 15:39:19 EDT 2026 [ 251.877821] Lustre: DEBUG MARKER: only running tests: 121-999 [ 253.295636] Lustre: DEBUG MARKER: excepting tests: 32newtarball [ 254.967881] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 258.564763] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 268.945063] Lustre: Unmounted lustre-client [ 377.205447] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 382.414452] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 404.128928] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 440.676687] Lustre: DEBUG MARKER: == conf-sanity test 121: failover MGS ==================== 15:42:43 (1790710963) [ 481.550698] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 486.968628] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 522.898210] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 569.444131] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 575.486711] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 608.700212] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mgc.*.mgs_server_uuid 1475 0 [ 639.301532] Lustre: DEBUG MARKER: == conf-sanity test 122a: Check OST sequence update ====== 15:46:02 (1790711162) [ 739.084629] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 744.493331] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 765.214437] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 765.827679] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 787.637581] Lustre: Unmounted lustre-client [ 816.125239] Key type lgssc unregistered [ 816.377521] LNet: 21986:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 816.383837] LNetError: 21986:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 817.446266] LNet: Removed LNI 192.168.206.58@tcp [ 818.387174] Key type .llcrypt unregistered [ 818.389618] Key type ._llcrypt unregistered [ 832.182525] Key type ._llcrypt registered [ 832.187470] Key type .llcrypt registered [ 833.412834] Lustre: DEBUG MARKER: == conf-sanity test 123aa: llog_print works with FIDs and simple names ========================================================== 15:49:16 (1790711356) [ 835.431256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 835.457524] alg: No test for adler32 (adler32-zlib) [ 836.587968] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 836.859904] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 838.527291] Key type lgssc registered [ 839.903659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 884.625088] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 889.357630] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 909.796243] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 912.563459] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 924.270818] Lustre: DEBUG MARKER: == conf-sanity test 123ab: llog_print params output values from set_param -P ========================================================== 15:50:46 (1790711446) [ 934.354743] Lustre: DEBUG MARKER: == conf-sanity test 123ac: llog_print with --start and --end ========================================================== 15:50:57 (1790711457) [ 942.152989] Lustre: DEBUG MARKER: == conf-sanity test 123ad: llog_print shows all records == 15:51:05 (1790711465) [ 953.828716] Lustre: DEBUG MARKER: == conf-sanity test 123ae: llog_cancel can cancel requested record ========================================================== 15:51:16 (1790711476) [ 979.120893] Lustre: DEBUG MARKER: == conf-sanity test 123af: llog_catlist can show all config files correctly ========================================================== 15:51:41 (1790711501) [ 993.425910] Lustre: DEBUG MARKER: == conf-sanity test 123ag: llog_print skips values deleted by set_param -P -d ========================================================== 15:51:56 (1790711516) [ 1006.281670] Lustre: DEBUG MARKER: == conf-sanity test 123ah: del_ost cancels config log entries correctly ========================================================== 15:52:09 (1790711529) [ 1014.364134] Lustre: Unmounted lustre-client [ 1014.751636] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 1016.607332] Lustre: Unmounted lustre-client [ 1042.800952] Key type lgssc unregistered [ 1043.095816] LNet: 30362:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1043.105025] LNetError: 30362:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1043.130255] LNet: Removed LNI 192.168.206.58@tcp [ 1043.823400] Key type .llcrypt unregistered [ 1043.825483] Key type ._llcrypt unregistered [ 1071.966337] Key type ._llcrypt registered [ 1071.968225] Key type .llcrypt registered [ 1072.512023] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1072.531221] alg: No test for adler32 (adler32-zlib) [ 1073.604150] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 1074.010527] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1075.743247] Key type lgssc registered [ 1076.838386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1149.643719] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1154.678913] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1180.227355] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1220.337378] Lustre: DEBUG MARKER: == conf-sanity test 123ai: llog_print display all non skipped records ========================================================== 15:55:43 (1790711743) [ 1250.249318] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1254.432375] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1271.742971] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1277.458409] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 1411.226757] Lustre: DEBUG MARKER: == conf-sanity test 123aj: check permanent TBF rules ===== 15:58:53 (1790711933) [ 1455.327921] Lustre: DEBUG MARKER: == conf-sanity test 123F: clear and reset all parameters using set_param -F ========================================================== 15:59:38 (1790711978) [ 1465.669510] Lustre: Unmounted lustre-client [ 1548.363718] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1554.287891] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1576.148737] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1577.076019] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 1582.843482] Lustre: Unmounted lustre-client [ 1611.075788] Key type lgssc unregistered [ 1611.378202] LNet: 41702:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1611.387639] LNetError: 41702:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1611.403047] LNet: Removed LNI 192.168.206.58@tcp [ 1612.350900] Key type .llcrypt unregistered [ 1612.354924] Key type ._llcrypt unregistered [ 1628.660893] Key type ._llcrypt registered [ 1628.664651] Key type .llcrypt registered [ 1630.246997] Lustre: DEBUG MARKER: == conf-sanity test 123G: clear and reset all parameters using apply_yaml ========================================================== 16:02:32 (1790712152) [ 1632.594256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1632.603943] alg: No test for adler32 (adler32-zlib) [ 1633.603638] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 1633.824597] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1635.511265] Key type lgssc registered [ 1636.949476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1684.124611] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1690.240709] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1711.730861] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1714.710284] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 1727.985343] Lustre: Unmounted lustre-client [ 1808.153672] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1813.713248] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1835.570485] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1836.367585] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 1842.379153] Lustre: Unmounted lustre-client [ 1870.375740] Key type lgssc unregistered [ 1870.743606] LNet: 47252:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1870.752226] LNetError: 47252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1870.777921] LNet: Removed LNI 192.168.206.58@tcp [ 1871.816357] Key type .llcrypt unregistered [ 1871.821070] Key type ._llcrypt unregistered [ 1888.694472] Key type ._llcrypt registered [ 1888.696582] Key type .llcrypt registered [ 1890.171605] Lustre: DEBUG MARKER: == conf-sanity test 123H: check concurent accesses with 'lctl llog_print ========================================================== 16:06:52 (1790712412) [ 1893.071186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1893.094938] alg: No test for adler32 (adler32-zlib) [ 1894.251470] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 1894.548099] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 1896.264172] Key type lgssc registered [ 1897.772707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1949.313258] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1956.214359] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1980.183034] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1983.053636] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 2086.682815] Lustre: DEBUG MARKER: == conf-sanity test 124: check failover after replace_nids ========================================================== 16:10:09 (1790712609) [ 2088.364654] Lustre: DEBUG MARKER: SKIP: conf-sanity test_124 needs MDT failover setup [ 2089.963947] Lustre: DEBUG MARKER: == conf-sanity test 126: mount in parallel shouldn't cause a crash ========================================================== 16:10:12 (1790712612) [ 2091.991426] Lustre: Unmounted lustre-client [ 2137.594216] Key type lgssc unregistered [ 2137.901363] LNet: 52612:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2137.912511] LNetError: 52612:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2137.957872] LNet: Removed LNI 192.168.206.58@tcp [ 2139.120770] Key type .llcrypt unregistered [ 2139.124765] Key type ._llcrypt unregistered [ 2167.758970] Key type ._llcrypt registered [ 2167.763638] Key type .llcrypt registered [ 2168.205644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2168.232988] alg: No test for adler32 (adler32-zlib) [ 2169.319736] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 2169.619696] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 2171.287381] Key type lgssc registered [ 2172.484572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2201.663891] Lustre: DEBUG MARKER: == conf-sanity test 127: direct io overwrite on full ost ========================================================== 16:12:04 (1790712724) [ 2213.341174] Key type lgssc unregistered [ 2213.667935] LNet: 55044:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2213.690306] LNetError: 55044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2213.737976] LNet: Removed LNI 192.168.206.58@tcp [ 2214.675840] Key type .llcrypt unregistered [ 2214.679066] Key type ._llcrypt unregistered [ 2228.415904] Key type ._llcrypt registered [ 2228.417948] Key type .llcrypt registered [ 2228.802892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2228.824826] alg: No test for adler32 (adler32-zlib) [ 2229.966268] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 2230.131456] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 2231.783388] Key type lgssc registered [ 2232.904805] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2277.107854] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2281.744530] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2300.469945] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2306.603274] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 2348.185208] Lustre: DEBUG MARKER: == conf-sanity test 128: Force using remote logs with --nolocallogs ========================================================== 16:14:31 (1790712871) [ 2349.712460] Lustre: DEBUG MARKER: SKIP: conf-sanity test_128 need separate mgs device [ 2351.769320] Lustre: DEBUG MARKER: == conf-sanity test 129: attempt to connect an OST with the same index should fail ========================================================== 16:14:34 (1790712874) [ 2353.275323] Lustre: Unmounted lustre-client [ 2422.658840] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2428.706314] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2506.566461] Lustre: DEBUG MARKER: == conf-sanity test 130: re-register an MDT after writeconf ========================================================== 16:17:09 (1790713029) [ 2589.890565] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 2594.399595] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2610.664642] Lustre: lustre-MDT0001-mdc-ffff896427b8f800: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2615.777086] Lustre: lustre-OST0000-osc-ffff896427b8f800: disconnect after 24s idle [ 2624.536268] LustreError: 63784:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.206.158@tcp: cfg command failed: rc = -17 [ 2624.545180] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [ 2624.545180] [ 2624.551235] LustreError: 63007:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [ 2635.830934] Lustre: DEBUG MARKER: == conf-sanity test 131: MDT backup restore with project ID and inheritance flag ========================================================== 16:19:18 (1790713158) [ 2692.859686] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2779.282019] Lustre: Unmounted lustre-client [ 2925.500538] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 2929.450375] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 2978.292525] Lustre: DEBUG MARKER: == conf-sanity test 132: hsm_actions processed after failover ========================================================== 16:25:00 (1790713500) [ 2980.035828] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3001.392962] Lustre: Unmounted lustre-client [ 3128.660749] Lustre: DEBUG MARKER: == conf-sanity test 133: stripe QOS: free space balance in a pool ========================================================== 16:27:31 (1790713651) [ 3130.185596] Lustre: DEBUG MARKER: SKIP: conf-sanity test_133 needs >= 4 OSTs [ 3131.968567] Lustre: DEBUG MARKER: == conf-sanity test 134: check_iam works without faults == 16:27:34 (1790713654) [ 3214.020721] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 3219.796779] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 3886.691762] Lustre: DEBUG MARKER: == conf-sanity test 135: check the behavior when changelog is wrapped around ========================================================== 16:40:09 (1790714409) [ 3888.722715] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3888.733375] Lustre: Skipped 3 previous similar messages [ 3899.102658] Lustre: Unmounted lustre-client [ 4018.926582] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4023.451238] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4043.815688] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4044.565257] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 6015.602682] Lustre: 95391:0:(llog_cat.c:310:llog_cat_process_or_fork()) lustre-MDT0000-mdc-ffff896405c84000: catlog [0xa:0x5:0x0] crosses index zero [ 6040.379157] Lustre: Unmounted lustre-client [ 6083.532877] Key type lgssc unregistered [ 6083.813157] LNet: 103611:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6083.822904] LNetError: 103611:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6083.843615] LNet: Removed LNI 192.168.206.58@tcp [ 6084.592259] Key type .llcrypt unregistered [ 6084.595608] Key type ._llcrypt unregistered [ 6093.581287] Key type ._llcrypt registered [ 6093.584593] Key type .llcrypt registered [ 6094.787770] Lustre: DEBUG MARKER: == conf-sanity test 136: don't panic with bad obdecho setup ========================================================== 17:16:57 (1790716617) [ 6110.063369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6110.133649] alg: No test for adler32 (adler32-zlib) [ 6111.168472] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 6111.410763] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 6113.087248] Key type lgssc registered [ 6114.350060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6203.551864] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6211.259782] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6236.336350] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6238.170448] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 6254.871317] Lustre: Unmounted lustre-client [ 6387.839095] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6393.538782] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6417.281078] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6460.960605] Lustre: DEBUG MARKER: == conf-sanity test 137: a new MDT should inherit pools, parameters and failnode ========================================================== 17:23:03 (1790716983) [ 6463.046377] Lustre: DEBUG MARKER: SKIP: conf-sanity test_137 needs >= 3 MDTs [ 6464.765481] Lustre: DEBUG MARKER: == conf-sanity test 138: MDT mount failure must not LBUG destroying recovery exports ========================================================== 17:23:07 (1790716987) [ 6513.265223] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6519.108352] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6540.750879] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6541.523276] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 6552.050477] Lustre: lustre-MDT0000-mdc-ffff89640fb98000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6567.398826] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [ 6567.417276] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0xc646a39cfd9d2cc1 to 0xc646a39cfd9d2d2a [ 6567.438381] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6573.407228] Lustre: 104378:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790717081/real 1790717081] req@00000000f89c83aa x1877702486272384/t0(0) o400->lustre-MDT0001-mdc-ffff89640fb98000@192.168.206.158@tcp:12/10 lens 224/224 e 0 to 1 dl 1790717097 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6573.438819] Lustre: lustre-MDT0001-mdc-ffff89640fb98000: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6576.627723] Lustre: 113951:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.158@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 6579.551222] Lustre: 104377:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790717086/real 1790717086] req@00000000bea8e13e x1877702486272768/t0(0) o400->lustre-MDT0001-mdc-ffff89640fb98000@192.168.206.158@tcp:12/10 lens 224/224 e 0 to 1 dl 1790717102 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6583.583177] Lustre: 104380:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790717091/real 1790717091] req@000000009b4daf20 x1877702486273152/t0(0) o400->lustre-MDT0001-mdc-ffff89640fb98000@192.168.206.158@tcp:12/10 lens 224/224 e 0 to 1 dl 1790717107 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6588.703990] Lustre: 104377:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790717096/real 1790717096] req@00000000f1bcc24c x1877702486273664/t0(0) o400->lustre-MDT0001-mdc-ffff89640fb98000@192.168.206.158@tcp:12/10 lens 224/224 e 0 to 1 dl 1790717112 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6591.859221] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [ 6591.870881] Lustre: 113951:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.206.158@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 6591.880191] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0xc646a39cfd9d2d2a to 0xc646a39cfd9d2f14 [ 6591.891954] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6609.267989] Lustre: lustre-MDT0001-mdc-ffff89640fb98000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6612.396637] LustreError: lustre-MDT0000-mdc-ffff89640fb98000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6612.416320] Lustre: lustre-MDT0000-mdc-ffff89640fb98000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6615.816776] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6617.307650] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6622.830488] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6624.237290] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6632.150905] Lustre: Unmounted lustre-client [ 6661.994539] Key type lgssc unregistered [ 6662.312179] LNet: 116317:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6662.326130] LNetError: 116317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6662.355027] LNet: Removed LNI 192.168.206.58@tcp [ 6663.092410] Key type .llcrypt unregistered [ 6663.097082] Key type ._llcrypt unregistered [ 6675.315380] Key type ._llcrypt registered [ 6675.318748] Key type .llcrypt registered [ 6676.971713] Lustre: DEBUG MARKER: == conf-sanity test 140: remove_updatelog script actions ========================================================== 17:26:39 (1790717199) [ 6680.041814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6680.137721] alg: No test for adler32 (adler32-zlib) [ 6681.262814] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 6681.487182] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 6683.167232] Key type lgssc registered [ 6684.505893] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6739.011498] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6745.846398] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6769.323933] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6772.184080] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 6782.447699] Lustre: lustre-MDT0000-mdc-ffff896407d11800: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6787.564804] Lustre: lustre-MDT0001-mdc-ffff896407d11800: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6803.935764] Lustre: 116890:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790717311/real 1790717311] req@0000000006463d99 x1877703084021632/t0(0) o400->MGC192.168.206.158@tcp@192.168.206.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1790717327 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6803.980612] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [ 6950.384351] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0x158dc42303b209ee to 0x158dc42303b22084 [ 6950.398828] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6960.166650] LustreError: lustre-MDT0000-mdc-ffff896407d11800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6960.202675] Lustre: lustre-MDT0000-mdc-ffff896407d11800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6965.227522] LustreError: lustre-MDT0001-mdc-ffff896407d11800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6965.244224] Lustre: lustre-MDT0001-mdc-ffff896407d11800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 6967.664638] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6968.702210] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6974.556274] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6976.268788] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6997.378745] Lustre: Unmounted lustre-client [ 7123.662915] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7129.354705] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7152.016032] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7193.060716] Lustre: DEBUG MARKER: == conf-sanity test 150: test setting max_cached_mb to a % ========================================================== 17:35:15 (1790717715) [ 7228.931469] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7235.682343] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7256.886590] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7257.543836] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 7263.055238] LustreError: 126351:0:(lproc_llite.c:618:ll_max_cached_mb_seq_write()) lustre: cannot set max_cached_mb=0 MB more than 3693 MB: rc = -34 [ 7269.349706] Lustre: DEBUG MARKER: == conf-sanity test 151a: damaged local config doesn't prevent mounting ========================================================== 17:36:32 (1790717792) [ 7270.843803] Lustre: Unmounted lustre-client [ 7299.262033] Key type lgssc unregistered [ 7299.593818] LNet: 127356:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7299.608101] LNetError: 127356:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7299.625752] LNet: Removed LNI 192.168.206.58@tcp [ 7300.352359] Key type .llcrypt unregistered [ 7300.355994] Key type ._llcrypt unregistered [ 7314.719408] Key type ._llcrypt registered [ 7314.724348] Key type .llcrypt registered [ 7315.185623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7315.198142] alg: No test for adler32 (adler32-zlib) [ 7316.228459] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 7316.515727] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 7318.255341] Key type lgssc registered [ 7319.684723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7404.918798] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7410.774632] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7431.915354] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7551.392978] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7558.004405] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7580.379931] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7623.364751] Lustre: DEBUG MARKER: == conf-sanity test 151b: -ENOSPC doesn't affect mount === 17:42:26 (1790718146) [ 7635.730412] Key type lgssc unregistered [ 7636.082479] LNet: 134580:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7636.091329] LNetError: 134580:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7636.109883] LNet: Removed LNI 192.168.206.58@tcp [ 7636.844961] Key type .llcrypt unregistered [ 7636.848138] Key type ._llcrypt unregistered [ 7649.884658] Key type ._llcrypt registered [ 7649.890641] Key type .llcrypt registered [ 7650.468430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7650.525963] alg: No test for adler32 (adler32-zlib) [ 7651.586562] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 7651.864826] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 7653.567456] Key type lgssc registered [ 7655.030636] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7707.168796] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7713.245067] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7735.758656] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7840.457444] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7845.789530] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7867.792214] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7909.061790] Lustre: DEBUG MARKER: == conf-sanity test 152: seq allocation error in OSP ===== 17:47:11 (1790718431) [ 7977.126946] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 7980.841212] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7988.009125] Lustre: DEBUG MARKER: ADD OST3 [ 7999.448632] Lustre: DEBUG MARKER: STOP OST3 [ 8003.040220] Lustre: lustre-OST0000-osc-ffff896420394000: disconnect after 24s idle [ 8013.818556] Lustre: lustre-OST0003-osc-ffff896420394000: Connection to lustre-OST0003 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8022.223174] Lustre: DEBUG MARKER: START OST3 again [ 8031.349082] Lustre: lustre-OST0003-osc-ffff896420394000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [ 8058.870197] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8058.886874] LustreError: 145390:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff896420394000: inode [0x240000bd0:0x1:0x0] mdc close failed: rc = -108 [ 8069.237978] Lustre: Unmounted lustre-client [ 8165.294717] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8170.795225] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8196.228229] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8230.521486] Lustre: DEBUG MARKER: == conf-sanity test 153a: bypass invalid NIDs quickly ==== 17:52:33 (1790718753) [ 8318.989815] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8324.525944] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8347.688832] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8417.112651] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8424.843607] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8446.644216] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8449.773423] LNetError: 134997:0:(lib-move.c:2151:lnet_handle_find_routed_path()) peer 192.168.252.112@tcp2 has no available nets [ 8450.100167] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 8455.824548] Lustre: Unmounted lustre-client [ 8485.056809] Key type lgssc unregistered [ 8503.839282] LNet: 1 local NIs in recovery (showing 1): 192.168.206.58@tcp [ 8509.924056] LNet: 154354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8509.931701] LNetError: 154354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8582.632645] LNet: Removed LNI 192.168.206.58@tcp [ 8583.320152] Key type .llcrypt unregistered [ 8583.323180] Key type ._llcrypt unregistered [ 8601.720806] Key type ._llcrypt registered [ 8601.726574] Key type .llcrypt registered [ 8603.485927] Lustre: DEBUG MARKER: == conf-sanity test 153b: added IPv6 NID support ========= 17:58:45 (1790719125) [ 8624.373464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8624.414953] alg: No test for adler32 (adler32-zlib) [ 8625.444295] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 8625.646423] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 8627.367218] Key type lgssc registered [ 8628.928227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8713.429992] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8720.747203] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8745.606230] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8877.180085] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 8882.187954] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8887.932940] Lustre: Unmounted lustre-client [ 8888.002061] LNetError: 155544:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5458:feff:fed3:1e63/1023: rc = -22 [ 8888.010864] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8888.313906] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 8888.515330] Lustre: Unmounted lustre-client [ 8889.400141] LNetError: 155545:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5458:feff:fed3:1e63/1023: rc = -22 [ 8889.410110] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8889.670816] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 8889.676023] Lustre: Skipped 1 previous similar message [ 8889.885709] Lustre: Unmounted lustre-client [ 8889.896241] Lustre: Skipped 1 previous similar message [ 8891.902069] LNetError: 155545:0:(lib-socket.c:308:lnet_sock_create()) Error trying to bind to fe80::5458:feff:fed3:1e63/1023: rc = -22 [ 8891.911424] LNetError: Unexpected error -22 connecting to 6699:7654::1234:1234:d84@tcp at host [6699:7654::1234:1234:d84]:988 [ 8892.282259] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 8892.285492] Lustre: Skipped 3 previous similar messages [ 8892.520531] Lustre: Unmounted lustre-client [ 8892.530113] Lustre: Skipped 3 previous similar messages [ 8893.321058] Lustre: 161470:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC6699:7654::1234:1234:d84@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8893.826546] Lustre: 161501:0:(obd_mount.c:175:lustre_add_mgc_failnodes()) MGC193.168.0.240@tcp: can't add failover NID 5031:db8:85a3:8d3:1319:8a2e:370:7348@tcp, rc = -75 [ 8901.224457] Lustre: DEBUG MARKER: == conf-sanity test 153c: don't stuck on unreached NID === 18:03:44 (1790719424) [ 8951.263257] LNet: 1 local NIs in recovery (showing 1): 192.168.206.58@tcp [ 9022.097396] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9027.384637] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9049.479686] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9133.671369] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9139.345838] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9158.518164] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9164.577759] LustreError: 167090:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.252.112@tcp: failed processing log, type 1: rc = -110 [ 9212.447280] LNet: 1 local NIs in recovery (showing 1): 192.168.206.58@tcp [ 9291.298473] LNet: 1 local NIs in recovery (showing 1): 192.168.206.58@tcp [ 9301.987375] LustreError: MGC192.168.252.112@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 9302.007859] Lustre: Unmounted lustre-client [ 9302.013248] Lustre: Skipped 3 previous similar messages [ 9302.025658] LustreError: 167090:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 9329.251923] Key type lgssc unregistered [ 9351.711161] LNet: 1 local NIs in recovery (showing 1): 192.168.206.58@tcp [ 9352.161961] LNet: 167547:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9352.187478] LNetError: 167547:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9421.885300] LNet: Removed LNI 192.168.206.58@tcp [ 9422.733381] Key type .llcrypt unregistered [ 9422.738458] Key type ._llcrypt unregistered [ 9441.457602] Key type ._llcrypt registered [ 9441.462933] Key type .llcrypt registered [ 9442.982721] Lustre: DEBUG MARKER: == conf-sanity test 154: expand .. on rename after MDT backup restore ========================================================== 18:12:45 (1790719965) [ 9460.776748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9460.795176] alg: No test for adler32 (adler32-zlib) [ 9461.944805] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [ 9462.134193] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [ 9463.783223] Key type lgssc registered [ 9465.041555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9587.877207] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 9592.757672] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9606.957802] Lustre: Unmounted lustre-client [ 9760.826531] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [ 9765.434996] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9771.035825] Lustre: Unmounted lustre-client [ 9831.240650] Lustre: DEBUG MARKER: == conf-sanity test 155: gap in seq allocation from ofd after restarting ========================================================== 18:19:14 (1790720354) [ 9910.732627] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9915.887656] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9936.627710] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10048.655224] LustreError: 168750:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [10048.727226] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10053.236465] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10067.353272] Lustre: Unmounted lustre-client [10183.773887] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10188.243209] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10207.204612] Lustre: DEBUG MARKER: == conf-sanity test 156: root_fid on export consistent with client mount ========================================================== 18:25:30 (1790720730) [10208.403986] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10208.413617] LustreError: 185952:0:(file.c:252:ll_close_inode_openhandle()) lustre-clilmv-ffff8964126fb800: inode [0x200000bd1:0x1:0x0] mdc close failed: rc = -108 [10218.759148] Lustre: Unmounted lustre-client [10393.994367] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10398.108204] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10403.890489] Lustre: Unmounted lustre-client [10404.304375] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10411.660643] Lustre: DEBUG MARKER: == conf-sanity test 157a: test allow_register for MDT registration and MGS restart ========================================================== 18:28:54 (1790720934) [10479.286585] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10483.588436] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10502.991433] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10503.697219] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10590.710809] Lustre: lustre-MDT0000-mdc-ffff8964181aa000: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [10600.936059] LustreError: lustre-OST0001-osc-ffff8964181aa000: operation ost_connect to node 192.168.206.158@tcp failed: rc = -19 [10606.063916] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [10606.085485] LustreError: lustre-MDT0000-mdc-ffff8964181aa000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10606.096053] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0xfa756c115a20ec77 to 0xfa756c115a20efdb [10606.106439] Lustre: lustre-MDT0000-mdc-ffff8964181aa000: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [10606.114179] Lustre: Skipped 1 previous similar message [10646.713169] Lustre: Unmounted lustre-client [10646.716095] Lustre: Skipped 1 previous similar message [10675.365855] Key type lgssc unregistered [10675.761664] LNet: 195173:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10675.767917] LNetError: 195173:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10675.797039] LNet: Removed LNI 192.168.206.58@tcp [10676.897239] Key type .llcrypt unregistered [10676.899063] Key type ._llcrypt unregistered [10692.225791] Key type ._llcrypt registered [10692.229140] Key type .llcrypt registered [10693.773931] Lustre: DEBUG MARKER: == conf-sanity test 157b: verify allow_register (block new OSTs, allow existing) ========================================================== 18:33:36 (1790721216) [10697.087970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10697.171301] alg: No test for adler32 (adler32-zlib) [10698.345833] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [10698.690282] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [10700.384342] Key type lgssc registered [10701.952849] Lustre: Echo OBD driver; http://www.lustre.org/ [10749.770073] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10754.037435] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10772.260661] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10775.193611] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [10872.831386] LustreError: lustre-OST0001-osc-ffff89642795e800: operation ost_connect to node 192.168.206.158@tcp failed: rc = -19 [10951.582094] Lustre: Unmounted lustre-client [10975.520589] Key type lgssc unregistered [10975.807460] LNet: 201202:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10975.824275] LNetError: 201202:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10975.840924] LNet: Removed LNI 192.168.206.58@tcp [10976.476096] Key type .llcrypt unregistered [10976.478784] Key type ._llcrypt unregistered [10985.703823] Key type ._llcrypt registered [10985.713696] Key type .llcrypt registered [10986.797455] Lustre: DEBUG MARKER: == conf-sanity test 160: MGC updates failnodes from all participants ========================================================== 18:38:29 (1790721509) [11008.901919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11008.921328] alg: No test for adler32 (adler32-zlib) [11009.962373] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [11010.170025] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11011.871271] Key type lgssc registered [11012.947131] Lustre: Echo OBD driver; http://www.lustre.org/ [11084.545762] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11089.447762] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11108.390777] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11143.970648] Key type lgssc unregistered [11144.286337] LNet: 206193:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11144.298326] LNetError: 206193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11144.321772] LNet: Removed LNI 192.168.206.58@tcp [11144.969509] Key type .llcrypt unregistered [11144.972952] Key type ._llcrypt unregistered [11262.249143] Key type ._llcrypt registered [11262.277865] Key type .llcrypt registered [11262.688798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11262.704581] alg: No test for adler32 (adler32-zlib) [11263.712517] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [11264.009112] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11265.698796] Key type lgssc registered [11266.964599] Lustre: Echo OBD driver; http://www.lustre.org/ [11311.035162] Lustre: DEBUG MARKER: == conf-sanity test 161: test '-o mgsname' option ======== 18:43:54 (1790721834) [11350.808977] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11358.184812] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11383.562499] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11386.315764] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [11388.471233] Lustre: Unmounted lustre-client [11388.842504] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [11389.179098] Lustre: Unmounted lustre-client [11396.858031] Lustre: Unmounted lustre-client [11433.448882] Key type lgssc unregistered [11433.731818] LNet: 211186:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11433.741501] LNetError: 211186:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11433.778927] LNet: Removed LNI 192.168.206.58@tcp [11434.548234] Key type .llcrypt unregistered [11434.551755] Key type ._llcrypt unregistered [11444.931146] Key type ._llcrypt registered [11444.933402] Key type .llcrypt registered [11446.369847] Lustre: DEBUG MARKER: == conf-sanity test 162: replace nids with -o noclient === 18:46:08 (1790721968) [11449.936443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11450.042098] alg: No test for adler32 (adler32-zlib) [11451.152900] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [11451.431532] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11453.119571] Key type lgssc registered [11454.678311] Lustre: Echo OBD driver; http://www.lustre.org/ [11507.418698] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11512.969976] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11534.799600] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11536.290900] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [11547.970619] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11550.016392] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11556.849700] Lustre: lustre-MDT0000-mdc-ffff8964067e2800: Connection to lustre-MDT0000 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [11567.081323] Lustre: lustre-MDT0001-mdc-ffff8964067e2800: Connection to lustre-MDT0001 (at 192.168.206.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [11578.273571] Lustre: 211869:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1790722086/real 1790722086] req@000000005e6e9202 x1877708085663488/t0(0) o400->MGC192.168.206.158@tcp@192.168.206.158@tcp:26/25 lens 224/224 e 0 to 1 dl 1790722102 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [11578.303337] LustreError: MGC192.168.206.158@tcp: Connection to MGS (at 192.168.206.158@tcp) was lost; in progress operations using this service will fail [11612.076719] Lustre: Evicted from MGS (at 192.168.206.158@tcp) after server handle changed from 0xf9e48e0f5b3f2ed8 to 0xf9e48e0f5b3f306e [11612.110397] Lustre: MGC192.168.206.158@tcp: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [11650.555954] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11652.414240] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in IDLE state after 0 sec [11652.598537] LustreError: lustre-MDT0000-mdc-ffff8964067e2800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [11652.633885] Lustre: lustre-MDT0000-mdc-ffff8964067e2800: Connection restored to 192.168.206.158@tcp (at 192.168.206.158@tcp) [11659.362747] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11661.310547] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [11664.521761] Lustre: Unmounted lustre-client [11699.128117] Key type lgssc unregistered [11699.454792] LNet: 216748:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11699.471506] LNetError: 216748:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11699.492932] LNet: Removed LNI 192.168.206.58@tcp [11700.517752] Key type .llcrypt unregistered [11700.519338] Key type ._llcrypt unregistered [11728.662575] Key type ._llcrypt registered [11728.667622] Key type .llcrypt registered [11728.878313] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11728.895950] alg: No test for adler32 (adler32-zlib) [11729.952572] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [11730.206232] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [11731.927236] Key type lgssc registered [11733.066084] Lustre: Echo OBD driver; http://www.lustre.org/ [11818.306583] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11823.539113] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11848.165836] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11890.836557] Lustre: DEBUG MARKER: == conf-sanity test 164: test expected_clients parameter and max client tracking ========================================================== 18:53:33 (1790722413) [11988.097827] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11993.645766] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12015.306212] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12016.939658] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [12021.095300] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [12022.338167] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [12022.342485] Lustre: Skipped 1 previous similar message [12026.900979] Lustre: Unmounted lustre-client [12029.728211] Lustre: Unmounted lustre-client [12031.367267] Lustre: Unmounted lustre-client [12034.604203] Lustre: Unmounted lustre-client [12034.608820] Lustre: Skipped 1 previous similar message [12044.634420] Lustre: Unmounted lustre-client [12073.467650] Key type lgssc unregistered [12073.756648] LNet: 225934:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12073.768451] LNetError: 225934:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12073.805269] LNet: Removed LNI 192.168.206.58@tcp [12074.411218] Key type .llcrypt unregistered [12074.416863] Key type ._llcrypt unregistered [12088.549514] Key type ._llcrypt registered [12088.552579] Key type .llcrypt registered [12089.909231] Lustre: DEBUG MARKER: == conf-sanity test 165: automatic at_min scaling based on expected clients ========================================================== 18:56:52 (1790722612) [12093.030678] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12093.127144] alg: No test for adler32 (adler32-zlib) [12094.265958] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12094.596824] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12096.305888] Key type lgssc registered [12097.728252] Lustre: Echo OBD driver; http://www.lustre.org/ [12155.515261] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12161.863276] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12185.582583] Lustre: DEBUG MARKER: oleg658-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12197.439640] Lustre: Mounted lustre-client - version 2.17.59_2_g598166e [12241.765441] Lustre: Unmounted lustre-client [12268.952217] Key type lgssc unregistered [12269.233718] LNet: 229890:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12269.242240] LNetError: 229890:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12269.266368] LNet: Removed LNI 192.168.206.58@tcp [12270.025278] Key type .llcrypt unregistered [12270.033589] Key type ._llcrypt unregistered [12281.411579] Key type ._llcrypt registered [12281.413516] Key type .llcrypt registered [12282.978157] Lustre: DEBUG MARKER: == conf-sanity test 200a: check CPU partitions =========== 19:00:05 (1790722805) [12296.468372] Key type .llcrypt unregistered [12296.477564] Key type ._llcrypt unregistered [12297.832399] Key type ._llcrypt registered [12297.844123] Key type .llcrypt registered [12298.429747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 4 [12298.459119] alg: No test for adler32 (adler32-zlib) [12299.747170] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12300.365977] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12302.247402] Key type lgssc registered [12303.985832] Lustre: Echo OBD driver; http://www.lustre.org/ [12334.187559] Key type lgssc unregistered [12334.597448] LNet: 232063:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12334.617610] LNetError: 232063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12334.629858] LNet: Removed LNI 192.168.206.58@tcp [12335.398769] Key type .llcrypt unregistered [12335.404932] Key type ._llcrypt unregistered [12335.956249] Key type ._llcrypt registered [12335.958530] Key type .llcrypt registered [12337.836409] Lustre: DEBUG MARKER: == conf-sanity test 200b: set CPU pattern using core selection ========================================================== 19:01:00 (1790722860) [12351.044881] Key type .llcrypt unregistered [12351.049879] Key type ._llcrypt unregistered [12352.452033] Key type ._llcrypt registered [12352.460152] Key type .llcrypt registered [12353.043671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12353.053861] alg: No test for adler32 (adler32-zlib) [12354.168786] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12354.458135] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12356.136276] Key type lgssc registered [12357.625507] Lustre: Echo OBD driver; http://www.lustre.org/ [12376.774355] Key type lgssc unregistered [12377.052917] LNet: 233638:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12377.060840] LNetError: 233638:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12377.080094] LNet: Removed LNI 192.168.206.58@tcp [12377.718908] Key type .llcrypt unregistered [12377.720524] Key type ._llcrypt unregistered [12387.653915] Key type ._llcrypt registered [12387.657181] Key type .llcrypt registered [12388.636806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12388.652967] alg: No test for adler32 (adler32-zlib) [12390.024858] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12390.277953] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12391.927258] Key type lgssc registered [12393.434721] Lustre: Echo OBD driver; http://www.lustre.org/ [12419.278870] Key type lgssc unregistered [12419.604195] LNet: 235417:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12419.613740] LNetError: 235417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12419.631091] LNet: Removed LNI 192.168.206.58@tcp [12420.350141] Key type .llcrypt unregistered [12420.358578] Key type ._llcrypt unregistered [12420.983647] Key type ._llcrypt registered [12420.984916] Key type .llcrypt registered [12422.531346] Lustre: DEBUG MARKER: == conf-sanity test 200c: set CPU pattern using NUMA node layout ========================================================== 19:02:25 (1790722945) [12435.546941] Key type .llcrypt unregistered [12435.549419] Key type ._llcrypt unregistered [12436.925242] Key type ._llcrypt registered [12436.927036] Key type .llcrypt registered [12437.215844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12437.245433] alg: No test for adler32 (adler32-zlib) [12438.423756] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12438.791194] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12440.567312] Key type lgssc registered [12442.289903] Lustre: Echo OBD driver; http://www.lustre.org/ [12461.616509] Key type lgssc unregistered [12461.886692] LNet: 236974:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12461.896425] LNetError: 236974:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12461.914851] LNet: Removed LNI 192.168.206.58@tcp [12462.750752] Key type .llcrypt unregistered [12462.763798] Key type ._llcrypt unregistered [12472.899946] Key type ._llcrypt registered [12472.905612] Key type .llcrypt registered [12473.832874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12473.854507] alg: No test for adler32 (adler32-zlib) [12474.944111] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12475.423971] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12477.199408] Key type lgssc registered [12478.918138] Lustre: Echo OBD driver; http://www.lustre.org/ [12500.282308] Key type lgssc unregistered [12500.727103] LNet: 238296:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12500.735038] LNetError: 238296:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12500.785108] LNet: Removed LNI 192.168.206.58@tcp [12501.636333] Key type .llcrypt unregistered [12501.638753] Key type ._llcrypt unregistered [12510.372079] Key type ._llcrypt registered [12510.414613] Key type .llcrypt registered [12510.717476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12510.736643] alg: No test for adler32 (adler32-zlib) [12511.993364] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12512.357838] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12514.119227] Key type lgssc registered [12515.440317] Lustre: Echo OBD driver; http://www.lustre.org/ [12533.071308] Key type lgssc unregistered [12533.517644] LNet: 239617:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12533.523827] LNetError: 239617:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12533.538336] LNet: Removed LNI 192.168.206.58@tcp [12534.353790] Key type .llcrypt unregistered [12534.356899] Key type ._llcrypt unregistered [12542.195694] Key type ._llcrypt registered [12542.204668] Key type .llcrypt registered [12542.497265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12542.514581] alg: No test for adler32 (adler32-zlib) [12543.749100] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12543.979836] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12545.687236] Key type lgssc registered [12546.953133] Lustre: Echo OBD driver; http://www.lustre.org/ [12572.653652] Key type lgssc unregistered [12572.926805] LNet: 241399:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12572.936695] LNetError: 241399:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12572.953533] LNet: Removed LNI 192.168.206.58@tcp [12573.801439] Key type .llcrypt unregistered [12573.807736] Key type ._llcrypt unregistered [12574.373710] Key type ._llcrypt registered [12574.377506] Key type .llcrypt registered [12576.063254] Lustre: DEBUG MARKER: == conf-sanity test 200d: set CPU pattern to exclude only CPU 1 ========================================================== 19:04:58 (1790723098) [12590.281633] Key type .llcrypt unregistered [12590.283287] Key type ._llcrypt unregistered [12591.411469] Key type ._llcrypt registered [12591.417617] Key type .llcrypt registered [12592.028216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12592.044634] alg: No test for adler32 (adler32-zlib) [12593.146488] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12593.422556] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12595.128303] Key type lgssc registered [12596.930813] Lustre: Echo OBD driver; http://www.lustre.org/ [12616.049151] Key type lgssc unregistered [12616.330583] LNet: 242963:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12616.344409] LNetError: 242963:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12616.374933] LNet: Removed LNI 192.168.206.58@tcp [12617.047796] Key type .llcrypt unregistered [12617.053596] Key type ._llcrypt unregistered [12624.853566] Key type ._llcrypt registered [12624.881829] Key type .llcrypt registered [12625.427417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12625.442659] alg: No test for adler32 (adler32-zlib) [12626.584336] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12626.899386] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12628.695410] Key type lgssc registered [12630.620715] Lustre: Echo OBD driver; http://www.lustre.org/ [12649.460229] Key type lgssc unregistered [12649.715565] LNet: 244294:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12649.720831] LNetError: 244294:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12649.738520] LNet: Removed LNI 192.168.206.58@tcp [12650.452908] Key type .llcrypt unregistered [12650.459866] Key type ._llcrypt unregistered [12660.767603] Key type ._llcrypt registered [12660.774723] Key type .llcrypt registered [12661.264633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12661.303666] alg: No test for adler32 (adler32-zlib) [12662.529785] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12662.822578] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12664.671254] Key type lgssc registered [12666.878390] Lustre: Echo OBD driver; http://www.lustre.org/ [12691.763371] Key type lgssc unregistered [12692.243236] LNet: 245619:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12692.256226] LNetError: 245619:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12692.316383] LNet: Removed LNI 192.168.206.58@tcp [12693.271412] Key type .llcrypt unregistered [12693.273641] Key type ._llcrypt unregistered [12701.608843] Key type ._llcrypt registered [12701.610607] Key type .llcrypt registered [12701.817087] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12701.847916] alg: No test for adler32 (adler32-zlib) [12702.954981] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12703.257835] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12704.983559] Key type lgssc registered [12707.112026] Lustre: Echo OBD driver; http://www.lustre.org/ [12728.363495] Key type lgssc unregistered [12728.682137] LNet: 246944:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12728.701465] LNetError: 246944:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12728.736021] LNet: Removed LNI 192.168.206.58@tcp [12729.861904] Key type .llcrypt unregistered [12729.867875] Key type ._llcrypt unregistered [12737.876542] Key type ._llcrypt registered [12737.886139] Key type .llcrypt registered [12738.271257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12738.282888] alg: No test for adler32 (adler32-zlib) [12739.559114] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12739.976458] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12741.615533] Key type lgssc registered [12743.180538] Lustre: Echo OBD driver; http://www.lustre.org/ [12761.722882] Key type lgssc unregistered [12762.059415] LNet: 248271:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12762.097638] LNetError: 248271:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12762.121323] LNet: Removed LNI 192.168.206.58@tcp [12762.970296] Key type .llcrypt unregistered [12762.973305] Key type ._llcrypt unregistered [12771.721089] Key type ._llcrypt registered [12771.727398] Key type .llcrypt registered [12772.396442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12772.413103] alg: No test for adler32 (adler32-zlib) [12773.631579] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12773.900116] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12775.584370] Key type lgssc registered [12777.039638] Lustre: Echo OBD driver; http://www.lustre.org/ [12794.127393] Key type lgssc unregistered [12794.512597] LNet: 249594:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12794.521157] LNetError: 249594:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12794.545753] LNet: Removed LNI 192.168.206.58@tcp [12795.369555] Key type .llcrypt unregistered [12795.372214] Key type ._llcrypt unregistered [12820.498631] Key type ._llcrypt registered [12820.501165] Key type .llcrypt registered [12821.904089] Lustre: DEBUG MARKER: == conf-sanity test 200e: set CPU pattern using relative core exclusion ========================================================== 19:09:04 (1790723344) [12832.791817] Key type .llcrypt unregistered [12832.798346] Key type ._llcrypt unregistered [12833.594169] Key type ._llcrypt registered [12833.597179] Key type .llcrypt registered [12834.233864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12834.265356] alg: No test for adler32 (adler32-zlib) [12835.406499] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12835.798759] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12837.527489] Key type lgssc registered [12838.980722] Lustre: Echo OBD driver; http://www.lustre.org/ [12855.511911] Key type lgssc unregistered [12855.733127] LNet: 251924:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12855.745323] LNetError: 251924:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12855.767259] LNet: Removed LNI 192.168.206.58@tcp [12856.382765] Key type .llcrypt unregistered [12856.384541] Key type ._llcrypt unregistered [12863.193583] Key type ._llcrypt registered [12863.195689] Key type .llcrypt registered [12863.515480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12863.528519] alg: No test for adler32 (adler32-zlib) [12864.620424] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12864.927832] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12866.631275] Key type lgssc registered [12868.404289] Lustre: Echo OBD driver; http://www.lustre.org/ [12885.069808] Key type lgssc unregistered [12885.335861] LNet: 253257:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12885.340086] LNetError: 253257:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12885.352719] LNet: Removed LNI 192.168.206.58@tcp [12886.101876] Key type .llcrypt unregistered [12886.105523] Key type ._llcrypt unregistered [12895.085707] Key type ._llcrypt registered [12895.094438] Key type .llcrypt registered [12895.599115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12895.615747] alg: No test for adler32 (adler32-zlib) [12896.795179] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12897.134080] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12898.863275] Key type lgssc registered [12900.316653] Lustre: Echo OBD driver; http://www.lustre.org/ [12917.913462] Key type lgssc unregistered [12918.148926] LNet: 254582:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12918.159711] LNetError: 254582:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12918.196497] LNet: Removed LNI 192.168.206.58@tcp [12918.873847] Key type .llcrypt unregistered [12918.876174] Key type ._llcrypt unregistered [12926.503841] Key type ._llcrypt registered [12926.507443] Key type .llcrypt registered [12927.160870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12927.177620] alg: No test for adler32 (adler32-zlib) [12928.440783] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12928.714276] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12930.415269] Key type lgssc registered [12932.057801] Lustre: Echo OBD driver; http://www.lustre.org/ [12959.249229] Key type lgssc unregistered [12959.522944] LNet: 256390:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12959.531911] LNetError: 256390:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12959.551491] LNet: Removed LNI 192.168.206.58@tcp [12960.281230] Key type .llcrypt unregistered [12960.287639] Key type ._llcrypt unregistered [12960.856725] Key type ._llcrypt registered [12960.859544] Key type .llcrypt registered [12962.859228] Lustre: DEBUG MARKER: == conf-sanity test 250: verify lcfg_erase removes filesystem parameters ========================================================== 19:11:24 (1790723484) [12983.395555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12983.411184] alg: No test for adler32 (adler32-zlib) [12984.550751] Lustre: Lustre: Build Version: 2.17.59_2_g598166e [12984.831812] LNet: Added LNI 192.168.206.58@tcp [8/256/0/180] [12986.538689] Key type lgssc registered [12987.973260] Lustre: Echo OBD driver; http://www.lustre.org/ [13222.041419] Lustre: DEBUG MARKER: == conf-sanity test 802a: simulate readonly device ======= 19:15:44 (1790723744) [13223.878106] Lustre: DEBUG MARKER: SKIP: conf-sanity test_802a ZFS specific test [13285.963968] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 13048 sec ========= 19:16:48 (1790723808) [13288.016899] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 19:16:50 (1790723810) === [13291.133228] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 19:16:54 (1790723814) === [13328.161788] Key type lgssc unregistered [13328.531844] LNet: 265466:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13328.539631] LNetError: 265466:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13328.571888] LNet: Removed LNI 192.168.206.58@tcp [13329.280294] Key type .llcrypt unregistered [13329.284728] Key type ._llcrypt unregistered