[ 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 392449548 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 2544MB 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, 524584K 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.003369] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005013] kvm-guest: setup PV IPIs [ 0.008000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.011128] LSM: Security Framework initializing [ 0.012054] Yama: becoming mindful. [ 0.013035] SELinux: Initializing. [ 0.014047] *** VALIDATE selinux *** [ 0.022903] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026626] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027110] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028097] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029102] *** VALIDATE tmpfs *** [ 0.031266] *** VALIDATE proc *** [ 0.032205] *** VALIDATE cgroup *** [ 0.033007] *** VALIDATE cgroup2 *** [ 0.034247] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035119] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037021] Spectre V2 : User space: Vulnerable [ 0.038007] Speculative Store Bypass: Vulnerable [ 0.040921] debug: unmapping init [mem 0xffffffff91059000-0xffffffff91060fff] [ 0.043951] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044571] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045014] ... version: 2 [ 0.045883] ... bit width: 48 [ 0.046007] ... generic registers: 4 [ 0.047007] ... value mask: 0000ffffffffffff [ 0.048010] ... max period: 00007fffffffffff [ 0.049007] ... fixed-purpose events: 3 [ 0.050006] ... event mask: 000000070000000f [ 0.052157] rcu: Hierarchical SRCU implementation. [ 0.054023] smp: Bringing up secondary CPUs ... [ 0.055386] x86: Booting SMP configuration: [ 0.056020] .... node #0, CPUs: #1 #2 #3 [ 0.060395] smp: Brought up 1 node, 4 CPUs [ 0.062013] smpboot: Max logical packages: 1 [ 0.063008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.123017] node 0 deferred pages initialised in 58ms [ 0.126092] devtmpfs: initialized [ 0.129565] x86/mm: Memory block size: 128MB [ 0.134000] gcov: version magic: 0x41383552 [ 0.138080] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.139126] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.140253] pinctrl core: initialized pinctrl subsystem [ 0.143179] [ 0.143968] ************************************************************* [ 0.150013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.155013] ** ** [ 0.157010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161009] ** ** [ 0.163011] ** This means that this kernel is built to expose internal ** [ 0.168014] ** IOMMU data structures, which may compromise security on ** [ 0.172015] ** your system. ** [ 0.174015] ** ** [ 0.177010] ** If you see this message and you are not debugging the ** [ 0.180011] ** kernel, report this immediately to your vendor! ** [ 0.183012] ** ** [ 0.186011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.189011] ************************************************************* [ 0.192569] NET: Registered protocol family 16 [ 0.195471] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.198053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.203064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.207089] cpuidle: using governor menu [ 0.209990] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.213612] PCI: Using configuration type 1 for base access [ 0.216257] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.226114] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.228032] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.230548] cryptd: max_cpu_qlen set to 1000 [ 0.234297] ACPI: Added _OSI(Module Device) [ 0.235000] ACPI: Added _OSI(Processor Device) [ 0.235010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.237010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.241830] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.250008] ACPI: Interpreter enabled [ 0.251060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.255032] ACPI: Using IOAPIC for interrupt routing [ 0.256106] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.258369] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.273212] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.275037] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.282032] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.289104] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.294511] acpiphp: Slot [2] registered [ 0.296125] acpiphp: Slot [5] registered [ 0.297000] acpiphp: Slot [6] registered [ 0.297122] acpiphp: Slot [3] registered [ 0.298000] acpiphp: Slot [4] registered [ 0.300065] acpiphp: Slot [7] registered [ 0.301054] acpiphp: Slot [8] registered [ 0.302058] acpiphp: Slot [9] registered [ 0.303042] acpiphp: Slot [10] registered [ 0.304088] acpiphp: Slot [11] registered [ 0.305075] acpiphp: Slot [12] registered [ 0.307084] acpiphp: Slot [13] registered [ 0.309043] acpiphp: Slot [14] registered [ 0.310077] acpiphp: Slot [15] registered [ 0.312057] acpiphp: Slot [16] registered [ 0.313046] acpiphp: Slot [17] registered [ 0.314155] acpiphp: Slot [18] registered [ 0.318123] acpiphp: Slot [19] registered [ 0.319145] acpiphp: Slot [20] registered [ 0.322166] acpiphp: Slot [21] registered [ 0.324162] acpiphp: Slot [22] registered [ 0.326232] acpiphp: Slot [23] registered [ 0.329255] acpiphp: Slot [24] registered [ 0.331155] acpiphp: Slot [25] registered [ 0.332087] acpiphp: Slot [26] registered [ 0.334095] acpiphp: Slot [27] registered [ 0.336099] acpiphp: Slot [28] registered [ 0.338131] acpiphp: Slot [29] registered [ 0.339074] acpiphp: Slot [30] registered [ 0.343137] acpiphp: Slot [31] registered [ 0.344285] PCI host bridge to bus 0000:00 [ 0.346081] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.349026] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.351021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.353035] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.355016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.357018] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.359212] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.363000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.366584] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.375019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.378822] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.382020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.383014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.385045] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.389600] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.397830] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.399039] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.406626] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.410080] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.421016] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.426013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.432000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.438015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.445021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.460023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.469000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.475014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.482014] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.496014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.507912] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.511467] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.514720] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.516345] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.518150] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.523119] iommu: Default domain type: Passthrough [ 0.524391] SCSI subsystem initialized [ 0.525000] ACPI: bus type USB registered [ 0.527076] usbcore: registered new interface driver usbfs [ 0.529065] usbcore: registered new interface driver hub [ 0.530066] usbcore: registered new device driver usb [ 0.532141] pps_core: LinuxPPS API ver. 1 registered [ 0.533006] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.535047] PTP clock support registered [ 0.537170] EDAC MC: Ver: 3.0.0 [ 0.538261] PCI: Using ACPI for IRQ routing [ 0.539695] NetLabel: Initializing [ 0.541007] NetLabel: domain hash size = 128 [ 0.542007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.544077] NetLabel: unlabeled traffic allowed by default [ 0.545341] vgaarb: loaded [ 0.547429] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.548011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.555737] clocksource: Switched to clocksource kvm-clock [ 0.730211] VFS: Disk quotas dquot_6.6.0 [ 0.732527] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.735974] *** VALIDATE ramfs *** [ 0.737116] *** VALIDATE hugetlbfs *** [ 0.738927] pnp: PnP ACPI init [ 0.741645] pnp: PnP ACPI: found 6 devices [ 0.773595] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.778730] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.781457] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.783918] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.786951] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.790165] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.793787] NET: Registered protocol family 2 [ 0.797920] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.805632] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.810731] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.818421] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.823932] TCP: Hash tables configured (established 65536 bind 65536) [ 0.828754] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.833849] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.837414] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.842353] NET: Registered protocol family 1 [ 0.847300] RPC: Registered named UNIX socket transport module. [ 0.848668] RPC: Registered udp transport module. [ 0.849636] RPC: Registered tcp transport module. [ 0.850649] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.852026] NET: Registered protocol family 44 [ 0.853082] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.854938] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.856644] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.858914] PCI: CLS 0 bytes, default 64 [ 0.860426] Unpacking initramfs... [ 2.319099] debug: unmapping init [mem 0xffffa0a9bcc64000-0xffffa0a9bffcffff] [ 2.322943] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.325030] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.327637] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.933290] Initialise system trusted keyrings [ 2.936034] Key type blacklist registered [ 2.937819] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.947341] zbud: loaded [ 2.950119] *** VALIDATE nfs *** [ 2.951176] *** VALIDATE nfs4 *** [ 2.952744] pstore: using deflate compression [ 2.955894] Platform Keyring initialized [ 3.074903] NET: Registered protocol family 38 [ 3.077228] Key type asymmetric registered [ 3.078521] Asymmetric key parser 'x509' registered [ 3.080135] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.082930] io scheduler mq-deadline registered [ 3.084626] io scheduler kyber registered [ 3.086344] io scheduler bfq registered [ 3.087920] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.090716] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.093550] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.096730] ACPI: Power Button [PWRF] [ 3.101990] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.109519] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.125712] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.162927] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.197458] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.202262] Non-volatile memory driver v1.3 [ 3.204508] Linux agpgart interface v0.103 [ 3.233482] virtio_blk virtio1: [vda] 145888 512-byte logical blocks (74.7 MB/71.2 MiB) [ 3.236987] vda: detected capacity change from 0 to 74694656 [ 3.256075] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.259595] vdb: detected capacity change from 0 to 1073741824 [ 3.273030] libphy: Fixed MDIO Bus: probed [ 3.276892] usbcore: registered new interface driver usbserial_generic [ 3.278786] usbserial: USB Serial support registered for generic [ 3.280667] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.284593] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.285898] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.288833] mousedev: PS/2 mouse device common for all mice [ 3.291759] rtc_cmos 00:05: RTC can wake from S4 [ 3.294762] rtc_cmos 00:05: registered as rtc0 [ 3.296516] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.299057] intel_pstate: CPU model not supported [ 3.301794] hid: raw HID events driver (C) Jiri Kosina [ 3.303570] usbcore: registered new interface driver usbhid [ 3.306034] usbhid: USB HID core driver [ 3.308289] drop_monitor: Initializing network drop monitor service [ 3.313167] Initializing XFRM netlink socket [ 3.315575] NET: Registered protocol family 10 [ 3.318672] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.320824] Segment Routing with IPv6 [ 3.329802] NET: Registered protocol family 17 [ 3.332792] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.336866] mpls_gso: MPLS GSO support [ 3.345531] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.348659] RAS: Correctable Errors collector initialized. [ 3.352085] AVX version of gcm_enc/dec engaged. [ 3.353830] AES CTR mode by8 optimization enabled [ 3.446950] sched_clock: Marking stable (3446869162, 0)->(4333190455, -886321293) [ 3.451255] registered taskstats version 1 [ 3.453969] Loading compiled-in X.509 certificates [ 3.457783] zswap: loaded using pool lzo/zbud [ 3.492432] Key type big_key registered [ 3.510339] Key type encrypted registered [ 3.512400] ima: No TPM chip found, activating TPM-bypass! [ 3.514814] ima: Allocated hash algorithm: sha1 [ 3.516789] ima: No architecture policies found [ 3.518889] evm: Initialising EVM extended attributes: [ 3.521103] evm: security.selinux [ 3.522593] evm: security.ima [ 3.523825] evm: security.capability [ 3.525422] evm: HMAC attrs: 0x1 [ 3.528181] rtc_cmos 00:05: setting system clock to 2026-08-11 16:01:25 UTC (1786464085) [ 3.537793] debug: unmapping init [mem 0xffffffff92003000-0xffffffff921fffff] [ 3.541906] debug: unmapping init [mem 0xffffffff90d82000-0xffffffff91058fff] [ 3.549225] Write protecting the kernel read-only data: 28672k [ 3.554259] debug: unmapping init [mem 0xffffffff8f403000-0xffffffff8f5fffff] [ 3.557488] debug: unmapping init [mem 0xffffffff8fd14000-0xffffffff8fdfffff] [ 3.594827] 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.605819] systemd[1]: Detected virtualization kvm. [ 3.608131] systemd[1]: Detected architecture x86-64. [ 3.610341] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.638260] systemd[1]: No hostname configured. [ 3.640308] systemd[1]: Set hostname to . [ 3.642632] random: systemd: uninitialized urandom read (16 bytes read) [ 3.645598] systemd[1]: Initializing machine ID from random generator. [ 3.753726] random: ln: uninitialized urandom read (6 bytes read) [ 3.905798] random: systemd: uninitialized urandom read (16 bytes read) [ 3.908321] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.912799] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.916194] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.068819] device-mapper: uevent: version 1.0.3 [ 5.071853] 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. 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 [ 6.557657] random: fast init done ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.764072] virtio_net virtio0 ens2: renamed from eth0 [ 7.397027] scsi host0: ata_piix [ 7.447885] scsi host1: ata_piix [ 7.450592] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.454056] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.201532] random: crng init done [ 12.203164] random: 7 urandom warning(s) missed due to ratelimiting [ 14.516763] dracut-initqueue[575]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ 16.102686] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Reached target Remote File Systems. [ 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 17.929892] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.411671] SELinux: Disabled at runtime. [ 18.485443] 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) [ 18.495186] systemd[1]: Detected virtualization kvm. [ 18.497486] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.978956] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.982328] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.992778] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.996454] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.999717] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.009705] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.064773] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on udev Control Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [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. [ 20.861068] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. [ 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 /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 21.655306] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 23.269285] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.303782] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 23.871928] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.936843] EDAC sbridge: Ver: 1.1.2 [ 27.265953] Key type dns_resolver registered [* [ 27.809925] NFS: Registering the id_resolver key type ] [ 27.812939] Key type id_resolver registered [ 27.814825] Key type id_legacy registered A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. [ 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... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... 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 ttyS0. [ OK ] Started Serial Getty on ttyS1. [ 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 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. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg306-client login: [ 67.591149] libcfs: loading out-of-tree module taints kernel. [ 67.640305] Key type ._llcrypt registered [ 67.642090] Key type .llcrypt registered [ 67.842867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 67.850052] alg: No test for adler32 (adler32-zlib) [ 68.880866] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 69.300352] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 70.952171] Key type lgssc registered [ 71.661165] Lustre: Echo OBD driver; http://www.lustre.org/ [ 150.017288] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 153.029625] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 163.528164] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing check_logdir /tmp/testlogs/ [ 165.604678] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing yml_node [ 167.748461] Lustre: DEBUG MARKER: Client: 2.17.56.50 [ 168.889135] Lustre: DEBUG MARKER: MDS: 2.17.56.50 [ 170.082049] Lustre: DEBUG MARKER: OSS: 2.17.56.50 [ 170.803309] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Tue Aug 11 12:04:12 EDT 2026 [ 175.584236] Lustre: lustre-OST0000-osc-ffffa0aa05e6b800: disconnect after 24s idle [ 178.639786] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 179.349275] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 180.159961] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 190.455147] Lustre: Unmounted lustre-client [ 264.514409] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 266.981270] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 278.106273] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 300.532789] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 12:06:21 (1786464381) [ 301.609315] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 302.402286] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 12:06:23 (1786464383) [ 317.480761] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 320.033441] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 329.800577] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 335.372384] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 341.311578] Lustre: Unmounted lustre-client [ 371.309359] Key type lgssc unregistered [ 371.451649] LNet: 15005:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 371.457058] LNetError: 15005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 371.471410] LNet: Removed LNI 192.168.203.6@tcp [ 371.851225] Key type .llcrypt unregistered [ 371.854428] Key type ._llcrypt unregistered [ 378.662203] Key type ._llcrypt registered [ 378.663840] Key type .llcrypt registered [ 379.410438] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 12:07:40 (1786464460) [ 380.128535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 380.150103] alg: No test for adler32 (adler32-zlib) [ 381.105762] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 381.240928] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 382.848375] Key type lgssc registered [ 383.506924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 418.329599] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 497.865858] Lustre: Unmounted lustre-client [ 508.921474] Key type lgssc unregistered [ 509.055884] LNet: 18893:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 509.060362] LNetError: 18893:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 509.070801] LNet: Removed LNI 192.168.203.6@tcp [ 509.359456] Key type .llcrypt unregistered [ 509.360861] Key type ._llcrypt unregistered [ 513.205602] Key type ._llcrypt registered [ 513.207279] Key type .llcrypt registered [ 513.783571] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 12:09:55 (1786464595) [ 514.723674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 514.732206] alg: No test for adler32 (adler32-zlib) [ 515.609266] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 515.708618] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 517.304195] Key type lgssc registered [ 517.785306] Lustre: Echo OBD driver; http://www.lustre.org/ [ 539.293116] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 541.689732] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 549.676745] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 556.027163] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 560.503215] Lustre: Unmounted lustre-client [ 590.269889] Key type lgssc unregistered [ 590.474414] LNet: 22502:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 590.484324] LNetError: 22502:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 590.508106] LNet: Removed LNI 192.168.203.6@tcp [ 591.100790] Key type .llcrypt unregistered [ 591.105876] Key type ._llcrypt unregistered [ 600.034215] Key type ._llcrypt registered [ 600.036356] Key type .llcrypt registered [ 600.779221] hrtimer: interrupt took 5146979 ns [ 601.160265] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 12:11:21 (1786464681) [ 603.016324] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 603.058805] alg: No test for adler32 (adler32-zlib) [ 604.052629] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 604.284773] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 605.968358] Key type lgssc registered [ 607.119625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 641.366823] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 644.945061] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 658.309966] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 659.663841] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 663.442959] Lustre: Unmounted lustre-client [ 701.081805] Key type lgssc unregistered [ 701.233176] LNet: 25689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 701.238282] LNetError: 25689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 701.253791] LNet: Removed LNI 192.168.203.6@tcp [ 701.670024] Key type .llcrypt unregistered [ 701.671952] Key type ._llcrypt unregistered [ 709.348490] Key type ._llcrypt registered [ 709.350364] Key type .llcrypt registered [ 710.035511] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 12:13:11 (1786464791) [ 711.003911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 711.043810] alg: No test for adler32 (adler32-zlib) [ 711.968550] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 712.107828] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 713.728601] Key type lgssc registered [ 714.345078] Lustre: Echo OBD driver; http://www.lustre.org/ [ 742.240278] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 745.929726] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 759.989609] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 762.477476] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 767.466568] Lustre: lustre-MDT0000-mdc-ffffa0aa05e6f800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 772.596654] Lustre: lustre-MDT0001-mdc-ffffa0aa05e6f800: Connection to lustre-MDT0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 786.495219] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 788.960521] Lustre: 26646:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786464854/real 1786464854] req@00000000187a2a7b x1873244107838976/t0(0) o400->MGC192.168.203.106@tcp@192.168.203.106@tcp:26/25 lens 224/224 e 0 to 1 dl 1786464870 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 788.973247] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 796.818568] Lustre: Unmounted lustre-client [ 811.193844] Key type lgssc unregistered [ 811.387690] LNet: 29339:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 811.392889] LNetError: 29339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 811.411788] LNet: Removed LNI 192.168.203.6@tcp [ 811.823851] Key type .llcrypt unregistered [ 811.828534] Key type ._llcrypt unregistered [ 820.737253] Key type ._llcrypt registered [ 820.738686] Key type .llcrypt registered [ 821.686661] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 12:15:02 (1786464902) [ 823.284478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 823.294381] alg: No test for adler32 (adler32-zlib) [ 824.269858] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 824.455173] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 826.096371] Key type lgssc registered [ 826.853109] Lustre: Echo OBD driver; http://www.lustre.org/ [ 854.684691] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 857.864671] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 867.152961] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 868.428896] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 883.695768] Lustre: lustre-OST0000-osc-ffffa0aa03f9b000: Connection to lustre-OST0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 894.866744] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 905.150044] Lustre: Unmounted lustre-client [ 932.273833] Key type lgssc unregistered [ 932.468404] LNet: 32951:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 932.473273] LNetError: 32951:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 932.484464] LNet: Removed LNI 192.168.203.6@tcp [ 932.834737] Key type .llcrypt unregistered [ 932.836502] Key type ._llcrypt unregistered [ 940.644802] Key type ._llcrypt registered [ 940.646573] Key type .llcrypt registered [ 941.448076] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 12:17:02 (1786465022) [ 942.623059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 942.635842] alg: No test for adler32 (adler32-zlib) [ 943.560083] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 943.702831] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 945.304164] Key type lgssc registered [ 945.960602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 969.341383] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 971.900352] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 981.233581] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 987.625306] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 993.427556] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 995.303177] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1005.947696] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-1112h18m07s [ 1006.761371] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1008.099743] Lustre: lustre-MDT0000-mdc-ffffa0aa11bf7800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1022.934723] LustreError: 36732:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffffa0aa11bf7800: can't stat MDS #0: rc = -19 [ 1023.594723] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1026.532747] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 1026.539911] Lustre: Evicted from MGS (at 192.168.203.106@tcp) after server handle changed from 0x939d54e8f72a7bd to 0x939d54e8f72aa56 [ 1026.544066] Lustre: MGC192.168.203.106@tcp: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 1029.963476] Lustre: DEBUG MARKER: Wait for df (36732) ... [ 1030.709992] Lustre: DEBUG MARKER: done [ 1031.176300] Lustre: Unmounted lustre-client [ 1056.397839] Key type lgssc unregistered [ 1056.531669] LNet: 37745:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1056.536278] LNetError: 37745:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1056.549230] LNet: Removed LNI 192.168.203.6@tcp [ 1056.846897] Key type .llcrypt unregistered [ 1056.848365] Key type ._llcrypt unregistered [ 1069.109868] Key type ._llcrypt registered [ 1069.111941] Key type .llcrypt registered [ 1069.690995] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 12:19:11 (1786465151) [ 1070.700355] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1070.720520] alg: No test for adler32 (adler32-zlib) [ 1071.636789] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1071.762937] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1073.376248] Key type lgssc registered [ 1073.960342] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1096.689233] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1099.146794] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1108.110963] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1109.363434] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1111.776104] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-1112h19m53s [ 1112.370437] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1115.038799] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1115.939546] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1136.096112] Lustre: 41877:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786465197/real 1786465197] req@00000000f1f0bd19 x1873244484277632/t0(0) o101->lustre-MDT0000-mdc-ffffa0aa139a0000@192.168.203.106@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786465217 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1136.106997] Lustre: lustre-MDT0000-mdc-ffffa0aa139a0000: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1136.119549] Lustre: lustre-MDT0000-mdc-ffffa0aa139a0000: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 1156.607539] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1157.724574] Lustre: DEBUG MARKER: done [ 1158.877194] Lustre: Unmounted lustre-client [ 1176.514218] Key type lgssc unregistered [ 1176.637868] LNet: 42761:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1176.641845] LNetError: 42761:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1176.650716] LNet: Removed LNI 192.168.203.6@tcp [ 1176.927358] Key type .llcrypt unregistered [ 1176.928852] Key type ._llcrypt unregistered [ 1188.186846] Key type ._llcrypt registered [ 1188.188393] Key type .llcrypt registered [ 1188.696173] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 12:21:10 (1786465270) [ 1189.233064] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1189.853376] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 12:21:11 (1786465271) [ 1191.205107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1191.228058] alg: No test for adler32 (adler32-zlib) [ 1192.103643] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1192.211201] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1193.800212] Key type lgssc registered [ 1194.219182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1205.871494] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 12:21:27 (1786465287) [ 1216.788177] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1218.584630] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1225.047926] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1226.221173] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1228.071879] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1228.851477] Lustre: Unmounted lustre-client [ 1238.482501] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1255.474311] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1257.311160] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1257.456521] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1258.329611] Lustre: Unmounted lustre-client [ 1284.014367] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1285.708082] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1285.869388] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1286.702130] Lustre: Unmounted lustre-client [ 1297.868678] Lustre: DEBUG MARKER: files compared the same [ 1312.298539] Key type lgssc unregistered [ 1312.412687] LNet: 50353:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1312.416191] LNetError: 50353:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1312.423452] LNet: Removed LNI 192.168.203.6@tcp [ 1312.666856] Key type .llcrypt unregistered [ 1312.667992] Key type ._llcrypt unregistered [ 1317.762548] Key type ._llcrypt registered [ 1317.764140] Key type .llcrypt registered [ 1318.243298] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 12:23:19 (1786465399) [ 1319.018243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1319.035039] alg: No test for adler32 (adler32-zlib) [ 1319.903781] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1319.996392] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1321.576171] Key type lgssc registered [ 1321.941841] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1339.289880] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1341.020472] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1347.403692] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1358.830039] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1360.487091] Lustre: Unmounted lustre-client [ 1385.895489] Key type lgssc unregistered [ 1386.013428] LNet: 53928:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1386.015710] LNetError: 53928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1386.023417] LNet: Removed LNI 192.168.203.6@tcp [ 1386.247969] Key type .llcrypt unregistered [ 1386.249437] Key type ._llcrypt unregistered [ 1391.175449] Key type ._llcrypt registered [ 1391.176892] Key type .llcrypt registered [ 1391.629578] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 12:24:33 (1786465473) [ 1392.381564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1392.406325] alg: No test for adler32 (adler32-zlib) [ 1393.261842] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1393.347942] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1394.928171] Key type lgssc registered [ 1395.301598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1455.262441] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1465.566951] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1467.456986] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1492.952590] Key type lgssc unregistered [ 1493.059393] LNet: 57407:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1493.061638] LNetError: 57407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1493.068278] LNet: Removed LNI 192.168.203.6@tcp [ 1493.282836] Key type .llcrypt unregistered [ 1493.284530] Key type ._llcrypt unregistered [ 1498.012938] Key type ._llcrypt registered [ 1498.013856] Key type .llcrypt registered [ 1498.441036] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 12:26:19 (1786465579) [ 1499.500721] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1499.515089] alg: No test for adler32 (adler32-zlib) [ 1500.362040] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1500.447599] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1502.032233] Key type lgssc registered [ 1502.402740] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1527.629910] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1532.992149] Lustre: Unmounted lustre-client [ 1553.354152] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 12:27:14 (1786465634) [ 1607.034391] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1618.381761] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1624.270711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1624.275504] LustreError: 63858:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffffa0aa11f22800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 1624.286172] Lustre: 58390:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.106@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 1634.569299] Lustre: Unmounted lustre-client [ 1665.685581] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 12:29:06 (1786465746) [ 1671.644610] Key type lgssc unregistered [ 1671.767064] LNet: 65076:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1671.771340] LNetError: 65076:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1671.780958] LNet: Removed LNI 192.168.203.6@tcp [ 1672.126954] Key type .llcrypt unregistered [ 1672.129450] Key type ._llcrypt unregistered [ 1678.074310] Key type ._llcrypt registered [ 1678.076605] Key type .llcrypt registered [ 1678.170513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1678.179444] alg: No test for adler32 (adler32-zlib) [ 1679.104777] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1679.241383] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1680.848512] Key type lgssc registered [ 1681.449183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1716.281783] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1721.740677] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1739.409909] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1742.441705] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1745.991162] Lustre: Unmounted lustre-client [ 1769.968246] Key type lgssc unregistered [ 1770.198965] LNet: 68086:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1770.201644] LNetError: 68086:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1770.211196] LNet: Removed LNI 192.168.203.6@tcp [ 1770.605379] Key type .llcrypt unregistered [ 1770.607318] Key type ._llcrypt unregistered [ 1778.395565] Key type ._llcrypt registered [ 1778.398869] Key type .llcrypt registered [ 1778.977362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1778.986921] alg: No test for adler32 (adler32-zlib) [ 1779.958179] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1780.174960] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1782.008474] Key type lgssc registered [ 1783.175442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1859.116509] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1863.181685] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1877.092954] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1887.761035] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1889.301495] Lustre: Unmounted lustre-client [ 1915.461432] Key type lgssc unregistered [ 1915.639160] LNet: 71516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1915.645525] LNetError: 71516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1915.669874] LNet: Removed LNI 192.168.203.6@tcp [ 1916.144194] Key type .llcrypt unregistered [ 1916.147089] Key type ._llcrypt unregistered [ 1926.945404] Key type ._llcrypt registered [ 1926.947507] Key type .llcrypt registered [ 1927.900556] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 12:33:28 (1786466008) [ 1929.494570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1929.514238] alg: No test for adler32 (adler32-zlib) [ 1930.477421] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 1930.648032] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 1932.272595] Key type lgssc registered [ 1933.023953] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1966.738223] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1970.505106] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1984.358782] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1995.301695] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 1999.593187] Lustre: Unmounted lustre-client [ 1999.964947] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2001.870164] Lustre: Unmounted lustre-client [ 2024.375471] Key type lgssc unregistered [ 2024.554133] LNet: 75199:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2024.559846] LNetError: 75199:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2024.573765] LNet: Removed LNI 192.168.203.6@tcp [ 2025.119705] Key type .llcrypt unregistered [ 2025.121885] Key type ._llcrypt unregistered [ 2033.120712] Key type ._llcrypt registered [ 2033.148752] Key type .llcrypt registered [ 2033.535397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2033.547179] alg: No test for adler32 (adler32-zlib) [ 2034.679478] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2034.980287] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2036.616571] Key type lgssc registered [ 2037.497341] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2071.697257] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2075.626492] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2090.099085] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2091.590826] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2094.600243] Lustre: Unmounted lustre-client [ 2124.351092] Key type lgssc unregistered [ 2124.529092] LNet: 78235:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2124.534254] LNetError: 78235:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2124.553993] LNet: Removed LNI 192.168.203.6@tcp [ 2125.029216] Key type .llcrypt unregistered [ 2125.031310] Key type ._llcrypt unregistered [ 2133.787748] Key type ._llcrypt registered [ 2133.789803] Key type .llcrypt registered [ 2134.611596] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 12:36:55 (1786466215) [ 2135.763525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2135.777516] alg: No test for adler32 (adler32-zlib) [ 2136.704270] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2136.859612] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2138.480591] Key type lgssc registered [ 2139.230991] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2170.882926] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2174.667434] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2186.790275] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2189.280215] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2196.557533] Lustre: lustre: root_squash is set to 0:0 [ 2196.578287] Lustre: lustre: nosquash_nids is cleared [ 2203.646357] Lustre: lustre: root_squash is set to 500:500 [ 2212.873662] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.6@tcp 192.168.0.[2,10]@tcp [ 2222.087890] Lustre: lustre: nosquash_nids set to 192.168.203.6/24@tcp [ 2223.466862] Lustre: Unmounted lustre-client [ 2248.969095] Key type lgssc unregistered [ 2249.123688] LNet: 82214:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2249.129355] LNetError: 82214:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2249.140097] LNet: Removed LNI 192.168.203.6@tcp [ 2249.532455] Key type .llcrypt unregistered [ 2249.534610] Key type ._llcrypt unregistered [ 2258.709280] Key type ._llcrypt registered [ 2258.711149] Key type .llcrypt registered [ 2259.493117] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 12:39:00 (1786466340) [ 2261.029798] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 2262.170446] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 12:39:03 (1786466343) [ 2263.445039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2263.465647] alg: No test for adler32 (adler32-zlib) [ 2264.448762] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2264.597592] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2266.232201] Key type lgssc registered [ 2267.069426] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2295.781085] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2298.892803] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2310.266183] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2311.704527] Lustre: lustre: root_squash is set to 500:500 [ 2311.727780] Lustre: lustre: nosquash_nids set to 192.168.203.6/24@tcp [ 2312.763467] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2319.257491] Lustre: Unmounted lustre-client [ 2335.467971] Key type lgssc unregistered [ 2335.631147] LNet: 86167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2335.636385] LNetError: 86167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2335.648144] LNet: Removed LNI 192.168.203.6@tcp [ 2335.989829] Key type .llcrypt unregistered [ 2335.991717] Key type ._llcrypt unregistered [ 2344.643438] Key type ._llcrypt registered [ 2344.645284] Key type .llcrypt registered [ 2345.558943] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 2346.547369] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 12:40:27 (1786466427) [ 2357.261560] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2357.271277] alg: No test for adler32 (adler32-zlib) [ 2358.263870] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2358.423845] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2360.056318] Key type lgssc registered [ 2360.720834] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2413.118167] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2416.452284] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2430.598450] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2482.927703] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2485.967370] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2497.354475] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2504.498316] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2523.783362] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0aa037e0800.ost_server_uuid 50 [ 2524.511877] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0aa037e0800.ost_server_uuid in FULL state after 0 sec [ 2524.746517] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2526.368571] Lustre: Unmounted lustre-client [ 2526.899971] Lustre: Unmounted lustre-client [ 2568.076229] Key type lgssc unregistered [ 2568.262926] LNet: 93823:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2568.267560] LNetError: 93823:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2568.282395] LNet: Removed LNI 192.168.203.6@tcp [ 2568.768792] Key type .llcrypt unregistered [ 2568.770157] Key type ._llcrypt unregistered [ 2586.317334] Key type ._llcrypt registered [ 2586.318869] Key type .llcrypt registered [ 2587.122427] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 12:44:28 (1786466668) [ 2597.780418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2597.795140] alg: No test for adler32 (adler32-zlib) [ 2598.752260] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2598.931696] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2600.624172] Key type lgssc registered [ 2601.379916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2642.674428] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2645.456387] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2657.043864] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2659.307394] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2674.662245] Lustre: lustre-OST0000-osc-ffffa0aa10daa800: Connection to lustre-OST0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2679.785705] Lustre: lustre-MDT0000-mdc-ffffa0aa10daa800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2680.672210] Lustre: 95182:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786466746/real 1786466746] req@0000000035c3a13e x1873246085453056/t0(0) o400->lustre-OST0000-osc-ffffa0aa10daa800@192.168.203.106@tcp:28/4 lens 224/224 e 0 to 1 dl 1786466762 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2685.792168] Lustre: 95182:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786466751/real 1786466751] req@00000000851a5647 x1873246085453568/t0(0) o400->lustre-OST0000-osc-ffffa0aa10daa800@192.168.203.106@tcp:28/4 lens 224/224 e 0 to 1 dl 1786466767 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2695.141279] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 2695.150338] Lustre: 98348:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.203.106@tcp: IR log lustre-cliir failed, not fatal: rc = -5 [ 2695.152829] Lustre: Evicted from MGS (at 192.168.203.106@tcp) after server handle changed from 0xbd4843884ef91eb4 to 0xbd4843884ef9215b [ 2695.161287] Lustre: MGC192.168.203.106@tcp: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 2698.342863] Lustre: lustre-MDT0000-mdc-ffffa0aa10daa800: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 2699.232179] Lustre: Unmounted lustre-client [ 2720.087092] Key type lgssc unregistered [ 2720.260576] LNet: 99470:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2720.265366] LNetError: 99470:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2720.278828] LNet: Removed LNI 192.168.203.6@tcp [ 2720.662750] Key type .llcrypt unregistered [ 2720.664140] Key type ._llcrypt unregistered [ 2728.688483] Key type ._llcrypt registered [ 2728.690011] Key type .llcrypt registered [ 2729.437307] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 12:46:50 (1786466810) [ 2741.818752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2741.834155] alg: No test for adler32 (adler32-zlib) [ 2742.754348] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 2742.900881] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 2744.512229] Key type lgssc registered [ 2745.140765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2766.303733] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2810.903268] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2813.845748] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2826.087810] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2827.420724] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 2828.565563] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 2829.634556] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 12:48:30 (1786466910) [ 2954.894241] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2965.197920] Lustre: Unmounted lustre-client [ 3047.829671] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3050.781689] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3062.332790] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3095.711160] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:52:57 (1786467177) [ 3138.840526] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3141.362935] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3151.564909] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3151.794942] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3153.021800] Lustre: Unmounted lustre-client [ 3164.435848] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 12:54:05 (1786467245) [ 3202.147732] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3204.417200] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3214.363364] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3219.958735] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3221.476345] Lustre: Unmounted lustre-client [ 3240.247979] Key type lgssc unregistered [ 3240.386182] LNet: 122426:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3240.391503] LNetError: 122426:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3240.400982] LNet: Removed LNI 192.168.203.6@tcp [ 3240.751640] Key type .llcrypt unregistered [ 3240.753317] Key type ._llcrypt unregistered [ 3248.659150] Key type ._llcrypt registered [ 3248.660805] Key type .llcrypt registered [ 3249.246381] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 12:55:30 (1786467330) [ 3250.182918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3250.230451] alg: No test for adler32 (adler32-zlib) [ 3251.138825] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 3251.256602] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 3252.864178] Key type lgssc registered [ 3253.342451] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3275.249879] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3277.381396] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3284.854949] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3291.125404] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3301.954655] Lustre: Unmounted lustre-client [ 3319.848698] Key type lgssc unregistered [ 3319.976905] LNet: 126011:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3319.980026] LNetError: 126011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3319.990185] LNet: Removed LNI 192.168.203.6@tcp [ 3320.266946] Key type .llcrypt unregistered [ 3320.268347] Key type ._llcrypt unregistered [ 3326.195188] Key type ._llcrypt registered [ 3326.196296] Key type .llcrypt registered [ 3326.677075] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 12:56:48 (1786467408) [ 3327.431468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3327.442543] alg: No test for adler32 (adler32-zlib) [ 3328.320617] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 3328.419937] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 3330.008218] Key type lgssc registered [ 3330.464458] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3353.075518] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3355.388119] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3363.316153] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3368.965197] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3374.054319] Lustre: lustre-OST0000-osc-ffffa0aa10b52800: Connection to lustre-OST0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3379.929346] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffffa0aa10b52800.ost_server_uuid 50 [ 3380.552477] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 3390.062181] Lustre: Unmounted lustre-client [ 3403.405567] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 12:58:04 (1786467484) [ 3416.450131] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3418.549152] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3426.997798] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3436.592344] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3436.823992] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3442.151274] Lustre: lustre-OST0000-osc-ffffa0aa12631800: Connection to lustre-OST0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3457.636713] Lustre: Unmounted lustre-client [ 3481.378266] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 12:59:22 (1786467562) [ 3492.538266] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3494.531705] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3501.547493] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3508.263641] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3508.449019] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3513.828615] Lustre: lustre-OST0000-osc-ffffa0aa03bec800: Connection to lustre-OST0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3525.293270] Lustre: Unmounted lustre-client [ 3557.925451] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 13:00:39 (1786467639) [ 3594.504495] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3596.974271] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3607.236946] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3640.572221] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3642.791820] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3650.396911] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3668.997610] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3697.183506] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3697.794572] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3698.136800] Lustre: Unmounted lustre-client [ 3709.013261] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 13:03:10 (1786467790) [ 3721.055249] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3723.265973] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3730.998671] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3748.117486] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3762.387506] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3790.281245] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3790.834761] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 3793.380086] Lustre: lustre-OST0001-osc-ffffa0aa05666800: Connection to lustre-OST0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3794.494419] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3804.793139] Lustre: Unmounted lustre-client [ 3844.088456] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 13:05:25 (1786467925) [ 3856.017085] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3858.035198] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3865.508489] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3865.683979] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3874.159128] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3875.735574] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 3880.811765] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa0aa10ac2800.ost_server_uuid 50 [ 3881.355325] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa0aa10ac2800.ost_server_uuid in FULL state after 0 sec [ 3882.093169] Lustre: Unmounted lustre-client [ 3882.189885] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 3882.193532] Lustre: Skipped 3 previous similar messages [ 3882.225987] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3882.613740] LustreError: 149330:0:(sec.c:403:import_sec_validate_get()) import 00000000c227d7f9 (NEW) with no sec [ 3922.402801] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 13:06:43 (1786468003) [ 3934.323522] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3936.308247] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3943.752535] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3950.865802] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3951.038700] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3951.075242] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 3958.767596] Lustre: Unmounted lustre-client [ 3958.769340] Lustre: Skipped 1 previous similar message [ 3979.400599] Key type lgssc unregistered [ 3979.526442] LNet: 152724:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3979.528554] LNetError: 152724:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3979.535489] LNet: Removed LNI 192.168.203.6@tcp [ 3979.757833] Key type .llcrypt unregistered [ 3979.759114] Key type ._llcrypt unregistered [ 3984.609339] Key type ._llcrypt registered [ 3984.610384] Key type .llcrypt registered [ 3985.056164] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 13:07:46 (1786468066) [ 3985.449671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3985.456675] alg: No test for adler32 (adler32-zlib) [ 3986.316128] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 3986.406181] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 3987.992158] Key type lgssc registered [ 3988.339945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4007.280334] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4009.177123] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4016.512379] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4023.038801] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4023.174086] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4024.214225] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4033.011030] LustreError: lustre-MDT0001-mdc-ffffa0aa10ac0000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 4033.021767] Lustre: lustre-MDT0001-mdc-ffffa0aa10ac0000: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 4035.874679] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4036.452601] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 4041.386125] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 4053.253074] Lustre: Unmounted lustre-client [ 4073.068718] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 13:09:14 (1786468154) [ 4109.393271] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4111.590878] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4120.469167] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4120.710932] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4132.623657] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4133.219366] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 4146.149166] Lustre: lustre-OST0001-osc-ffffa0aa06138000: Connection to lustre-OST0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4149.421370] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4159.682875] Lustre: Unmounted lustre-client [ 4183.946708] Key type lgssc unregistered [ 4184.085248] LNet: 162305:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4184.089685] LNetError: 162305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4184.100306] LNet: Removed LNI 192.168.203.6@tcp [ 4184.409373] Key type .llcrypt unregistered [ 4184.411138] Key type ._llcrypt unregistered [ 4196.384845] Key type ._llcrypt registered [ 4196.386040] Key type .llcrypt registered [ 4196.907226] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 13:11:18 (1786468278) [ 4197.767473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4197.784583] alg: No test for adler32 (adler32-zlib) [ 4198.645827] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4198.742585] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4200.328286] Key type lgssc registered [ 4200.782094] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4219.793491] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4221.748984] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4228.894847] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4230.080615] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4266.897665] Lustre: Unmounted lustre-client [ 4285.168395] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4285.398513] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4317.574223] Lustre: Unmounted lustre-client [ 4335.022883] Key type lgssc unregistered [ 4335.146388] LNet: 166733:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4335.150936] LNetError: 166733:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4335.158703] LNet: Removed LNI 192.168.203.6@tcp [ 4335.432669] Key type .llcrypt unregistered [ 4335.434143] Key type ._llcrypt unregistered [ 4340.822540] Key type ._llcrypt registered [ 4340.823558] Key type .llcrypt registered [ 4341.295342] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 13:13:42 (1786468422) [ 4342.129611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4342.188926] alg: No test for adler32 (adler32-zlib) [ 4343.041945] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4343.123804] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4344.704111] Key type lgssc registered [ 4345.074894] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4363.308583] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4365.229952] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4371.976350] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4383.731492] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4390.351260] Lustre: Unmounted lustre-client [ 4407.602507] Key type lgssc unregistered [ 4407.735707] LNet: 170479:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4407.739853] LNetError: 170479:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4407.748083] LNet: Removed LNI 192.168.203.6@tcp [ 4408.005337] Key type .llcrypt unregistered [ 4408.006869] Key type ._llcrypt unregistered [ 4412.082971] Key type ._llcrypt registered [ 4412.093973] Key type .llcrypt registered [ 4412.242740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4412.248488] alg: No test for adler32 (adler32-zlib) [ 4413.113846] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4413.192292] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4414.768200] Key type lgssc registered [ 4415.174756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4432.073121] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4433.953505] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4440.371127] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4450.805168] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4453.580137] Lustre: Unmounted lustre-client [ 4466.610384] Key type lgssc unregistered [ 4466.738521] LNet: 173533:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4466.741216] LNetError: 173533:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4466.747733] LNet: Removed LNI 192.168.203.6@tcp [ 4466.997848] Key type .llcrypt unregistered [ 4466.999557] Key type ._llcrypt unregistered [ 4472.219296] Key type ._llcrypt registered [ 4472.220418] Key type .llcrypt registered [ 4472.680537] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 13:15:54 (1786468554) [ 4473.484555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4473.491440] alg: No test for adler32 (adler32-zlib) [ 4474.336797] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4474.422194] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4476.000159] Key type lgssc registered [ 4476.356634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4493.463906] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4495.138298] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4501.425436] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4511.728968] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4517.952124] Lustre: Unmounted lustre-client [ 4535.128243] Key type lgssc unregistered [ 4535.252665] LNet: 177274:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4535.255613] LNetError: 177274:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4535.265700] LNet: Removed LNI 192.168.203.6@tcp [ 4535.559826] Key type .llcrypt unregistered [ 4535.561188] Key type ._llcrypt unregistered [ 4539.929537] Key type ._llcrypt registered [ 4539.930673] Key type .llcrypt registered [ 4540.109126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4540.114775] alg: No test for adler32 (adler32-zlib) [ 4540.985986] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4541.089671] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4542.680231] Key type lgssc registered [ 4543.114485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4560.232988] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4561.853441] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4568.249214] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4578.801656] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4581.568152] Lustre: Unmounted lustre-client [ 4594.688562] Key type lgssc unregistered [ 4594.807527] LNet: 180328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4594.810316] LNetError: 180328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4594.819771] LNet: Removed LNI 192.168.203.6@tcp [ 4595.084888] Key type .llcrypt unregistered [ 4595.086511] Key type ._llcrypt unregistered [ 4600.510792] Key type ._llcrypt registered [ 4600.512098] Key type .llcrypt registered [ 4600.952658] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 13:18:02 (1786468682) [ 4608.001703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4608.009543] alg: No test for adler32 (adler32-zlib) [ 4608.881727] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4608.985508] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4610.584179] Key type lgssc registered [ 4610.996087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4639.148611] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4640.804123] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4648.391245] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4670.835660] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 13:19:12 (1786468752) [ 4681.462730] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4683.294382] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4690.098229] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4700.655771] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4702.576044] Lustre: Unmounted lustre-client [ 4719.845969] Key type lgssc unregistered [ 4719.971564] LNet: 187403:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4719.973930] LNetError: 187403:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4719.983638] LNet: Removed LNI 192.168.203.6@tcp [ 4720.215687] Key type .llcrypt unregistered [ 4720.218477] Key type ._llcrypt unregistered [ 4725.382182] Key type ._llcrypt registered [ 4725.383189] Key type .llcrypt registered [ 4725.820300] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 4726.352440] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 13:20:07 (1786468807) [ 4731.940202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4731.992602] alg: No test for adler32 (adler32-zlib) [ 4732.851726] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4732.943418] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4734.528115] Key type lgssc registered [ 4734.887101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4768.045243] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4769.813911] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4777.592698] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4784.252606] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4785.442974] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 4791.658199] Lustre: Unmounted lustre-client [ 4854.973836] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4856.711413] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4864.350605] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4886.196647] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 13:22:47 (1786468967) [ 4886.672649] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 4887.169755] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 13:22:48 (1786468968) [ 4929.603723] Key type lgssc unregistered [ 4929.713580] LNet: 198798:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4929.716755] LNetError: 198798:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4929.724444] LNet: Removed LNI 192.168.203.6@tcp [ 4929.964198] Key type .llcrypt unregistered [ 4929.965278] Key type ._llcrypt unregistered [ 4934.853607] Key type ._llcrypt registered [ 4934.854919] Key type .llcrypt registered [ 4935.289816] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 13:23:36 (1786469016) [ 4957.066652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4957.102245] alg: No test for adler32 (adler32-zlib) [ 4957.979836] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 4958.080730] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 4959.672184] Key type lgssc registered [ 4960.101313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4974.292711] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4975.924218] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4995.441868] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5006.456295] Key type lgssc unregistered [ 5006.581426] LNet: 202516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5006.584237] LNetError: 202516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5006.596354] LNet: Removed LNI 192.168.203.6@tcp [ 5006.855771] Key type .llcrypt unregistered [ 5006.857055] Key type ._llcrypt unregistered [ 5012.284651] Key type ._llcrypt registered [ 5012.285914] Key type .llcrypt registered [ 5012.756118] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 13:24:54 (1786469094) [ 5013.681206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5013.692404] alg: No test for adler32 (adler32-zlib) [ 5014.558449] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5014.667742] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5016.256192] Key type lgssc registered [ 5016.644956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5047.546391] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5052.094758] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5069.369845] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5074.961173] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5080.038248] Lustre: lustre-MDT0000-mdc-ffffa0aa150ae800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5085.160739] Lustre: lustre-MDT0001-mdc-ffffa0aa150ae800: Connection to lustre-MDT0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5095.395339] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 5095.407580] Lustre: Evicted from MGS (at 192.168.203.106@tcp) after server handle changed from 0x4ff5c1129958395e to 0x4ff5c112995852a2 [ 5095.415665] Lustre: MGC192.168.203.106@tcp: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5102.057744] Lustre: 205624:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.203.106@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 5120.833901] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5120.998435] LustreError: lustre-MDT0000-mdc-ffffa0aa150ae800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5121.025763] Lustre: lustre-MDT0000-mdc-ffffa0aa150ae800: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5122.665506] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 1 sec [ 5125.441345] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5126.217696] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5127.495128] Lustre: Unmounted lustre-client [ 5147.561449] Key type lgssc unregistered [ 5147.731441] LNet: 207461:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5147.735413] LNetError: 207461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5147.744139] LNet: Removed LNI 192.168.203.6@tcp [ 5148.138226] Key type .llcrypt unregistered [ 5148.140325] Key type ._llcrypt unregistered [ 5155.151063] Key type ._llcrypt registered [ 5155.152799] Key type .llcrypt registered [ 5155.755858] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 13:27:17 (1786469237) [ 5156.813091] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5156.828285] alg: No test for adler32 (adler32-zlib) [ 5157.735837] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5157.840086] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5159.448187] Key type lgssc registered [ 5160.001248] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5183.792360] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5186.388586] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5213.783179] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5216.357439] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5234.744881] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5245.481796] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5273.678989] Key type lgssc unregistered [ 5273.813225] LNet: 212741:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5273.820195] LNetError: 212741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5273.833508] LNet: Removed LNI 192.168.203.6@tcp [ 5274.182168] Key type .llcrypt unregistered [ 5274.183895] Key type ._llcrypt unregistered [ 5300.417190] Key type ._llcrypt registered [ 5300.419377] Key type .llcrypt registered [ 5301.157807] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 13:29:42 (1786469382) [ 5319.616280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5319.624319] alg: No test for adler32 (adler32-zlib) [ 5320.545127] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5320.683316] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5322.288185] Key type lgssc registered [ 5322.832385] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5366.114723] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5368.678837] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5380.670811] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5402.941327] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 13:31:24 (1786469484) [ 5406.614664] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 13:31:27 (1786469487) [ 5427.733320] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5431.604716] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5444.617927] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5447.006335] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5451.062118] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 5452.179856] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5453.187627] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 5454.158248] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 5457.382204] Lustre: lustre-MDT0000-mdc-ffffa0aa10ac4800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5472.739973] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 5472.752734] Lustre: Evicted from MGS (at 192.168.203.106@tcp) after server handle changed from 0xd48244305b85fcef to 0xd48244305b85fe62 [ 5472.759317] Lustre: MGC192.168.203.106@tcp: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5477.865923] Lustre: 220624:0:(mgc_request.c:1899:mgc_process_log()) MGC192.168.203.106@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 5486.702621] Lustre: lustre-MDT0000-mdc-ffffa0aa10ac4800: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5489.296934] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5490.120756] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5492.454418] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 5498.342851] Lustre: lustre-MDT0000-mdc-ffffa0aa10ac4800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5508.578093] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 5508.587966] Lustre: Evicted from MGS (at 192.168.203.106@tcp) after server handle changed from 0xd48244305b85fe62 to 0xd48244305b86026e [ 5508.593520] Lustre: MGC192.168.203.106@tcp: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5513.323105] Lustre: lustre-MDT0000-mdc-ffffa0aa10ac4800: Connection restored to 192.168.203.106@tcp (at 192.168.203.106@tcp) [ 5515.873361] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5516.607986] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 5518.568978] Lustre: Unmounted lustre-client [ 5559.000153] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5561.909975] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5571.785312] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5572.048832] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5582.937657] Lustre: Unmounted lustre-client [ 5602.314570] Key type lgssc unregistered [ 5602.452288] LNet: 225203:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5602.460807] LNetError: 225203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5602.476642] LNet: Removed LNI 192.168.203.6@tcp [ 5602.792363] Key type .llcrypt unregistered [ 5602.794203] Key type ._llcrypt unregistered [ 5610.103518] Key type ._llcrypt registered [ 5610.105082] Key type .llcrypt registered [ 5610.738307] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 13:34:52 (1786469692) [ 5612.325805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5612.334462] alg: No test for adler32 (adler32-zlib) [ 5613.227405] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5613.383174] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5615.000181] Key type lgssc registered [ 5615.526726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5636.302757] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5638.526727] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5647.577785] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5655.022268] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5661.931141] Lustre: Unmounted lustre-client [ 5699.757247] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5702.325625] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5711.343956] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5716.999513] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5723.391125] Lustre: Unmounted lustre-client [ 5761.790756] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5764.388825] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5773.062396] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5778.424873] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5778.796770] Lustre: Unmounted lustre-client [ 5796.789567] Key type lgssc unregistered [ 5796.918409] LNet: 232241:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5796.922375] LNetError: 232241:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5796.932047] LNet: Removed LNI 192.168.203.6@tcp [ 5797.219151] Key type .llcrypt unregistered [ 5797.220822] Key type ._llcrypt unregistered [ 5803.683510] Key type ._llcrypt registered [ 5803.685355] Key type .llcrypt registered [ 5804.231398] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 13:38:05 (1786469885) [ 5805.456896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5805.477292] alg: No test for adler32 (adler32-zlib) [ 5806.356541] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5806.461321] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5808.056169] Key type lgssc registered [ 5808.541634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5826.789217] Key type lgssc unregistered [ 5826.924654] LNet: 234557:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5826.928418] LNetError: 234557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5826.939387] LNet: Removed LNI 192.168.203.6@tcp [ 5827.235041] Key type .llcrypt unregistered [ 5827.236295] Key type ._llcrypt unregistered [ 5838.069413] Key type ._llcrypt registered [ 5838.075993] Key type .llcrypt registered [ 5838.244244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5838.257777] alg: No test for adler32 (adler32-zlib) [ 5839.171139] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 5839.280907] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 5840.880653] Key type lgssc registered [ 5841.375462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5877.300895] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5879.828138] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5889.836405] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5921.847239] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 5922.494580] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 13:40:03 (1786470003) [ 5935.150363] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5937.387415] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5945.630867] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5955.760848] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5956.996426] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 5960.772611] LustreError: lustre-OST0001-osc-ffffa0aa11ca1800: operation ost_statfs to node 192.168.203.106@tcp failed: rc = -107 [ 5960.777119] Lustre: lustre-OST0001-osc-ffffa0aa11ca1800: Connection to lustre-OST0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5961.096652] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5971.390497] Lustre: Unmounted lustre-client [ 6003.718155] Key type lgssc unregistered [ 6003.833907] LNet: 241654:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6003.838858] LNetError: 241654:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6003.850801] LNet: Removed LNI 192.168.203.6@tcp [ 6004.124971] Key type .llcrypt unregistered [ 6004.126577] Key type ._llcrypt unregistered [ 6015.619049] Key type ._llcrypt registered [ 6015.620844] Key type .llcrypt registered [ 6016.183711] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 13:41:37 (1786470097) [ 6020.759490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6020.818106] alg: No test for adler32 (adler32-zlib) [ 6021.702126] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6021.801468] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6023.384173] Key type lgssc registered [ 6023.811086] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6042.398249] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6044.798458] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6056.368604] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 13:42:17 (1786470137) [ 6068.223772] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6070.508089] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6078.061133] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6084.572411] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6087.740208] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6089.718451] Lustre: Unmounted lustre-client [ 6147.767561] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6149.734331] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6181.874545] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6187.510373] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6188.022582] Lustre: Unmounted lustre-client [ 6205.399276] Key type lgssc unregistered [ 6205.518520] LNet: 249328:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6205.520617] LNetError: 249328:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6205.527868] LNet: Removed LNI 192.168.203.6@tcp [ 6205.773292] Key type .llcrypt unregistered [ 6205.775269] Key type ._llcrypt unregistered [ 6215.089373] Key type ._llcrypt registered [ 6215.090767] Key type .llcrypt registered [ 6215.257968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6215.272385] alg: No test for adler32 (adler32-zlib) [ 6216.128632] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6216.223135] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6217.808164] Key type lgssc registered [ 6218.207220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6247.400903] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6249.116513] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6257.046812] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6273.989129] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 6274.478845] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 13:45:56 (1786470356) [ 6284.131201] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6285.860443] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6292.504281] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6304.242228] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6305.131396] Lustre: Unmounted lustre-client [ 6322.524519] Key type lgssc unregistered [ 6322.628573] LNet: 255935:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6322.632531] LNetError: 255935:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6322.639721] LNet: Removed LNI 192.168.203.6@tcp [ 6322.878331] Key type .llcrypt unregistered [ 6322.879803] Key type ._llcrypt unregistered [ 6328.210535] Key type ._llcrypt registered [ 6328.211529] Key type .llcrypt registered [ 6328.709216] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 6329.274299] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 13:46:50 (1786470410) [ 6332.309287] Key type .llcrypt unregistered [ 6332.310287] Key type ._llcrypt unregistered [ 6334.959587] Key type ._llcrypt registered [ 6334.960881] Key type .llcrypt registered [ 6335.122683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6335.128112] alg: No test for adler32 (adler32-zlib) [ 6335.986740] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6336.070614] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6337.656146] Key type lgssc registered [ 6338.034825] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6356.393391] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6366.195170] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6366.712198] Lustre: Unmounted lustre-client [ 6384.017870] Key type lgssc unregistered [ 6384.121500] LNet: 259385:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6384.124242] LNetError: 259385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6384.132683] LNet: Removed LNI 192.168.203.6@tcp [ 6384.362700] Key type .llcrypt unregistered [ 6384.363688] Key type ._llcrypt unregistered [ 6389.432920] Key type ._llcrypt registered [ 6389.433844] Key type .llcrypt registered [ 6389.878163] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 13:47:51 (1786470471) [ 6390.689297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6390.703531] alg: No test for adler32 (adler32-zlib) [ 6391.566683] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6391.661627] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6393.248167] Key type lgssc registered [ 6393.638090] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6453.593495] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6464.094066] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6465.878541] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6467.056634] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6467.518183] Lustre: Unmounted lustre-client [ 6484.891478] Key type lgssc unregistered [ 6485.020681] LNet: 262918:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6485.023412] LNetError: 262918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6485.032674] LNet: Removed LNI 192.168.203.6@tcp [ 6485.292280] Key type .llcrypt unregistered [ 6485.293564] Key type ._llcrypt unregistered [ 6490.513605] Key type ._llcrypt registered [ 6490.515081] Key type .llcrypt registered [ 6490.984412] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 13:49:32 (1786470572) [ 6491.737561] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6491.745383] alg: No test for adler32 (adler32-zlib) [ 6492.593975] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6492.675815] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6494.256125] Key type lgssc registered [ 6494.627653] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6512.333953] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6514.117759] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6520.640442] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6531.061436] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6536.163540] Lustre: lustre-MDT0000-mdc-ffffa0aa05f01800: Connection to lustre-MDT0000 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6538.742556] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6538.751384] LustreError: 266062:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6538.848862] LustreError: MGC192.168.203.106@tcp: Connection to MGS (at 192.168.203.106@tcp) was lost; in progress operations using this service will fail [ 6538.869135] Lustre: Unmounted lustre-client [ 6549.390563] Key type lgssc unregistered [ 6549.504401] LNet: 266478:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6549.507386] LNetError: 266478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6549.519300] LNet: Removed LNI 192.168.203.6@tcp [ 6549.784482] Key type .llcrypt unregistered [ 6549.785716] Key type ._llcrypt unregistered [ 6555.138669] Key type ._llcrypt registered [ 6555.140533] Key type .llcrypt registered [ 6555.619842] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 13:50:37 (1786470637) [ 6556.314697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6556.367223] alg: No test for adler32 (adler32-zlib) [ 6557.225515] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6557.331749] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6558.920134] Key type lgssc registered [ 6559.312970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6576.600801] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6578.371905] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6585.016818] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6595.574140] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6600.676364] Lustre: lustre-MDT0001-mdc-ffffa0aa15179000: Connection to lustre-MDT0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6611.755683] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6612.135350] Lustre: Unmounted lustre-client [ 6622.820550] Key type lgssc unregistered [ 6622.953458] LNet: 270061:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6622.955918] LNetError: 270061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6622.966440] LNet: Removed LNI 192.168.203.6@tcp [ 6623.211725] Key type .llcrypt unregistered [ 6623.213074] Key type ._llcrypt unregistered [ 6628.259203] Key type ._llcrypt registered [ 6628.260538] Key type .llcrypt registered [ 6628.710214] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 13:51:50 (1786470710) [ 6634.645636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6634.686391] alg: No test for adler32 (adler32-zlib) [ 6635.543899] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6635.635457] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6637.216192] Key type lgssc registered [ 6637.622676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6674.330255] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6675.479382] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6693.347616] Lustre: lustre-MDT0001-mdc-ffffa0aa077f0800: Connection to lustre-MDT0001 (at 192.168.203.106@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6704.851070] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6715.130187] Lustre: Unmounted lustre-client [ 6732.112236] Key type lgssc unregistered [ 6732.222530] LNet: 275829:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6732.224936] LNetError: 275829:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6732.232457] LNet: Removed LNI 192.168.203.6@tcp [ 6732.455510] Key type .llcrypt unregistered [ 6732.456502] Key type ._llcrypt unregistered [ 6737.916380] Key type ._llcrypt registered [ 6737.917760] Key type .llcrypt registered [ 6738.586858] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 13:53:39 (1786470819) [ 6739.448229] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [ 6740.516208] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 13:53:41 (1786470821) [ 6741.521600] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [ 6742.424169] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 13:53:43 (1786470823) [ 6743.542208] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [ 6744.692247] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 13:53:45 (1786470825) [ 6745.704844] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [ 6746.786911] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 13:53:47 (1786470827) [ 6747.861839] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [ 6748.766825] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 13:53:50 (1786470830) [ 6753.977571] Key type .llcrypt unregistered [ 6753.981034] Key type ._llcrypt unregistered [ 6758.287282] Key type ._llcrypt registered [ 6758.338557] Key type .llcrypt registered [ 6758.599449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6758.611337] alg: No test for adler32 (adler32-zlib) [ 6759.603467] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 6759.787308] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 6761.416175] Key type lgssc registered [ 6762.070663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6795.411547] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6797.592401] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6806.380543] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6807.626369] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6808.159190] Lustre: Unmounted lustre-client [ 6831.484250] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 13:55:12 (1786470912) [ 6843.707124] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6845.789384] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6866.039098] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6881.778474] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 6882.177208] Lustre: Unmounted lustre-client [ 6911.845117] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 13:56:33 (1786470993) [ 6920.544309] LNet: 1 local NIs in recovery (showing 1): 192.168.203.6@tcp [ 7504.858708] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7507.387695] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7529.660848] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7581.691876] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 14:07:43 (1786471663) [ 7586.299588] Key type lgssc unregistered [ 7586.463050] LNet: 289320:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7586.466463] LNetError: 289320:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7586.479776] LNet: Removed LNI 192.168.203.6@tcp [ 7586.799877] Key type .llcrypt unregistered [ 7586.801468] Key type ._llcrypt unregistered [ 7656.011268] Key type ._llcrypt registered [ 7656.013243] Key type .llcrypt registered [ 7656.310219] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7656.320384] alg: No test for adler32 (adler32-zlib) [ 7657.219640] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 7657.341760] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 7658.944416] Key type lgssc registered [ 7659.541389] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7703.186730] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7705.652379] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7715.207424] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7743.842552] Key type lgssc unregistered [ 7743.989574] LNet: 293005:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7743.993348] LNetError: 293005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7744.002453] LNet: Removed LNI 192.168.203.6@tcp [ 7744.291265] Key type .llcrypt unregistered [ 7744.292992] Key type ._llcrypt unregistered [ 7774.267209] Key type ._llcrypt registered [ 7774.268559] Key type .llcrypt registered [ 7774.850705] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 14:10:56 (1786471856) [ 7783.524427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7783.530554] alg: No test for adler32 (adler32-zlib) [ 7784.401678] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 7784.495611] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 7786.088939] Key type lgssc registered [ 7786.582581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7807.073270] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 14:11:28 (1786471888) [ 7811.877453] Key type lgssc unregistered [ 7811.997521] LNet: 296559:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7812.000446] LNetError: 296559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7812.010893] LNet: Removed LNI 192.168.203.6@tcp [ 7812.287755] Key type .llcrypt unregistered [ 7812.289463] Key type ._llcrypt unregistered [ 7816.445555] Key type ._llcrypt registered [ 7816.447217] Key type .llcrypt registered [ 7816.653067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7816.659052] alg: No test for adler32 (adler32-zlib) [ 7817.545090] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 7817.656926] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 7819.264152] Key type lgssc registered [ 7819.707027] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7842.866969] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7845.235644] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7854.814324] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7963.476889] LNet: Added LNI 192.168.203.6@tcp50 [8/256/0/180] [ 7964.376301] LNet: Removed LNI 192.168.203.6@tcp [ 7965.813268] Lustre: 300917:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffffa0aa077f2800: config has no valid NIDs, force dynamic NIDs [ 7966.879117] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 7966.945243] Lustre: Unmounted lustre-client [ 7968.042143] Key type lgssc unregistered [ 7968.199461] LNet: 301181:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7968.202618] LNetError: 301181:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7968.216344] LNet: Removed LNI 192.168.203.6@tcp50 [ 7968.462922] Key type .llcrypt unregistered [ 7968.464204] Key type ._llcrypt unregistered [ 7978.911829] Key type ._llcrypt registered [ 7978.915069] Key type .llcrypt registered [ 7979.108875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7979.115418] alg: No test for adler32 (adler32-zlib) [ 7980.005572] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 7980.115197] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 7981.720176] Key type lgssc registered [ 7982.231236] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8011.253800] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8013.348272] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8016.067123] LNet: Added LNI 192.168.203.6@tcp50 [8/256/0/180] [ 8016.404648] LNet: Removed LNI 192.168.203.6@tcp [ 8017.524353] Lustre: 303862:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffffa0aa06fde800: config has no valid NIDs, force dynamic NIDs [ 8018.587020] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 8018.788197] Lustre: Unmounted lustre-client [ 8038.744884] Key type lgssc unregistered [ 8038.906141] LNet: 304734:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8038.909117] LNetError: 304734:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8038.921152] LNet: Removed LNI 192.168.203.6@tcp50 [ 8039.165599] Key type .llcrypt unregistered [ 8039.167174] Key type ._llcrypt unregistered [ 8043.236327] Key type ._llcrypt registered [ 8043.237737] Key type .llcrypt registered [ 8043.765209] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 14:15:25 (1786472125) [ 8050.701297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8050.709793] alg: No test for adler32 (adler32-zlib) [ 8051.606136] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 8051.720459] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 8053.328189] Key type lgssc registered [ 8053.853643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8087.226800] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8089.609461] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8099.532519] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8119.846279] Key type lgssc unregistered [ 8119.973549] LNet: 309465:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8119.977964] LNetError: 309465:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8119.986616] LNet: Removed LNI 192.168.203.6@tcp [ 8120.275936] Key type .llcrypt unregistered [ 8120.277357] Key type ._llcrypt unregistered [ 8124.560299] Key type ._llcrypt registered [ 8124.562024] Key type .llcrypt registered [ 8125.116288] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 14:16:46 (1786472206) [ 8126.002416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8126.038154] alg: No test for adler32 (adler32-zlib) [ 8126.911798] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 8127.009924] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 8128.608163] Key type lgssc registered [ 8129.067368] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8149.832258] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8151.883259] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8159.325972] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8169.981126] Lustre: Mounted lustre-client - version 2.17.56_50_g7b00149 [ 8192.852094] Lustre: Mounted fs15246-client - version 2.17.56_50_g7b00149 [ 8197.641720] Lustre: Unmounted fs15246-client [ 8211.170939] Lustre: Unmounted lustre-client [ 8228.630699] Key type lgssc unregistered [ 8228.741628] LNet: 314305:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8228.745311] LNetError: 314305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8228.757775] LNet: Removed LNI 192.168.203.6@tcp [ 8229.020683] Key type .llcrypt unregistered [ 8229.021761] Key type ._llcrypt unregistered [ 8232.788395] Key type ._llcrypt registered [ 8232.789462] Key type .llcrypt registered [ 8233.295653] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 14:18:34 (1786472314) [ 8233.934400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8233.945064] alg: No test for adler32 (adler32-zlib) [ 8234.830343] Lustre: Lustre: Build Version: 2.17.56_50_g7b00149 [ 8234.931406] LNet: Added LNI 192.168.203.6@tcp [8/256/0/180] [ 8236.520191] Key type lgssc registered [ 8236.949939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8282.650516] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8284.592564] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8292.797311] Lustre: DEBUG MARKER: oleg306-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8342.574591] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8172 sec ========== 14:20:24 (1786472424) [ 8343.101365] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:20:24 (1786472424) === [ 8344.177966] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:20:25 (1786472425) === [ 8355.820896] Key type lgssc unregistered [ 8355.956532] LNet: 323535:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8355.960291] LNetError: 323535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8355.968574] LNet: Removed LNI 192.168.203.6@tcp [ 8356.240846] Key type .llcrypt unregistered [ 8356.242505] Key type ._llcrypt unregistered