[ 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 464088676 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003240] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008766] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.011127] LSM: Security Framework initializing [ 0.013030] Yama: becoming mindful. [ 0.014041] SELinux: Initializing. [ 0.015073] *** VALIDATE selinux *** [ 0.023825] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028100] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029161] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031110] *** VALIDATE tmpfs *** [ 0.033188] *** VALIDATE proc *** [ 0.034246] *** VALIDATE cgroup *** [ 0.035022] *** VALIDATE cgroup2 *** [ 0.036278] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038040] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040029] Spectre V2 : User space: Vulnerable [ 0.041009] Speculative Store Bypass: Vulnerable [ 0.044171] debug: unmapping init [mem 0xffffffffa0259000-0xffffffffa0260fff] [ 0.047208] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048750] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049025] ... version: 2 [ 0.050013] ... bit width: 48 [ 0.051013] ... generic registers: 4 [ 0.052016] ... value mask: 0000ffffffffffff [ 0.053013] ... max period: 00007fffffffffff [ 0.054012] ... fixed-purpose events: 3 [ 0.055015] ... event mask: 000000070000000f [ 0.056329] rcu: Hierarchical SRCU implementation. [ 0.058587] smp: Bringing up secondary CPUs ... [ 0.059618] x86: Booting SMP configuration: [ 0.060030] .... node #0, CPUs: #1 #2 #3 [ 0.064011] smp: Brought up 1 node, 4 CPUs [ 0.066019] smpboot: Max logical packages: 1 [ 0.067014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.145021] node 0 deferred pages initialised in 76ms [ 0.149009] devtmpfs: initialized [ 0.150324] x86/mm: Memory block size: 128MB [ 0.153025] gcov: version magic: 0x41383552 [ 0.155290] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.156090] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.157248] pinctrl core: initialized pinctrl subsystem [ 0.158182] [ 0.158682] ************************************************************* [ 0.159016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.160016] ** ** [ 0.161018] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.162013] ** ** [ 0.163016] ** This means that this kernel is built to expose internal ** [ 0.164015] ** IOMMU data structures, which may compromise security on ** [ 0.165019] ** your system. ** [ 0.166013] ** ** [ 0.167019] ** If you see this message and you are not debugging the ** [ 0.168014] ** kernel, report this immediately to your vendor! ** [ 0.169014] ** ** [ 0.170015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.171018] ************************************************************* [ 0.172777] NET: Registered protocol family 16 [ 0.173472] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.174059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.175064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.177056] cpuidle: using governor menu [ 0.179508] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.182594] PCI: Using configuration type 1 for base access [ 0.185125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.197092] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.199101] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.203093] cryptd: max_cpu_qlen set to 1000 [ 0.205225] ACPI: Added _OSI(Module Device) [ 0.206013] ACPI: Added _OSI(Processor Device) [ 0.208012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.209013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.214198] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.221014] ACPI: Interpreter enabled [ 0.222063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.224012] ACPI: Using IOAPIC for interrupt routing [ 0.226762] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.230413] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.240464] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.243086] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.246032] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.251150] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.256593] acpiphp: Slot [2] registered [ 0.258276] acpiphp: Slot [5] registered [ 0.260235] acpiphp: Slot [6] registered [ 0.262205] acpiphp: Slot [3] registered [ 0.264151] acpiphp: Slot [4] registered [ 0.265190] acpiphp: Slot [7] registered [ 0.267167] acpiphp: Slot [8] registered [ 0.268152] acpiphp: Slot [9] registered [ 0.270152] acpiphp: Slot [10] registered [ 0.272172] acpiphp: Slot [11] registered [ 0.273151] acpiphp: Slot [12] registered [ 0.275175] acpiphp: Slot [13] registered [ 0.277143] acpiphp: Slot [14] registered [ 0.278136] acpiphp: Slot [15] registered [ 0.279149] acpiphp: Slot [16] registered [ 0.281137] acpiphp: Slot [17] registered [ 0.283148] acpiphp: Slot [18] registered [ 0.284156] acpiphp: Slot [19] registered [ 0.286131] acpiphp: Slot [20] registered [ 0.287166] acpiphp: Slot [21] registered [ 0.289110] acpiphp: Slot [22] registered [ 0.292148] acpiphp: Slot [23] registered [ 0.294103] acpiphp: Slot [24] registered [ 0.295121] acpiphp: Slot [25] registered [ 0.297119] acpiphp: Slot [26] registered [ 0.298128] acpiphp: Slot [27] registered [ 0.300117] acpiphp: Slot [28] registered [ 0.301112] acpiphp: Slot [29] registered [ 0.303265] acpiphp: Slot [30] registered [ 0.304084] acpiphp: Slot [31] registered [ 0.306124] PCI host bridge to bus 0000:00 [ 0.307021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.310021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.311017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.313020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.315022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.317028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.319195] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.320971] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.323522] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.329483] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.334157] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.337028] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.339019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.342017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.344627] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.347777] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.350043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.352792] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.355789] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.364930] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.369016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.374509] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.379019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.385016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.397016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.408870] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.415025] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.422022] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.444025] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.454925] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.457406] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.460393] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.466417] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.468250] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.472291] iommu: Default domain type: Passthrough [ 0.474601] SCSI subsystem initialized [ 0.476151] ACPI: bus type USB registered [ 0.478155] usbcore: registered new interface driver usbfs [ 0.480093] usbcore: registered new interface driver hub [ 0.482092] usbcore: registered new device driver usb [ 0.484207] pps_core: LinuxPPS API ver. 1 registered [ 0.486012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.489069] PTP clock support registered [ 0.491094] EDAC MC: Ver: 3.0.0 [ 0.492467] PCI: Using ACPI for IRQ routing [ 0.494789] NetLabel: Initializing [ 0.496017] NetLabel: domain hash size = 128 [ 0.498015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.500092] NetLabel: unlabeled traffic allowed by default [ 0.502159] vgaarb: loaded [ 0.504286] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.506021] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.511377] clocksource: Switched to clocksource kvm-clock [ 0.625498] VFS: Disk quotas dquot_6.6.0 [ 0.627134] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.629901] *** VALIDATE ramfs *** [ 0.631122] *** VALIDATE hugetlbfs *** [ 0.632859] pnp: PnP ACPI init [ 0.635281] pnp: PnP ACPI: found 6 devices [ 0.652241] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.658499] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.660774] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.662489] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.664925] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.667404] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.670292] NET: Registered protocol family 2 [ 0.672878] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.677845] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.681751] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.687282] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.694362] TCP: Hash tables configured (established 65536 bind 65536) [ 0.697675] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.700772] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.703627] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.706729] NET: Registered protocol family 1 [ 0.709996] RPC: Registered named UNIX socket transport module. [ 0.712210] RPC: Registered udp transport module. [ 0.713902] RPC: Registered tcp transport module. [ 0.715568] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.718046] NET: Registered protocol family 44 [ 0.720126] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.722938] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.725250] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.728781] PCI: CLS 0 bytes, default 64 [ 0.730729] Unpacking initramfs... [ 2.213791] debug: unmapping init [mem 0xffff8b4a3cc64000-0xffff8b4a3ffcffff] [ 2.220839] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.223582] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.227037] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.723996] Initialise system trusted keyrings [ 2.725864] Key type blacklist registered [ 2.727952] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.737820] zbud: loaded [ 2.741174] *** VALIDATE nfs *** [ 2.742486] *** VALIDATE nfs4 *** [ 2.744259] pstore: using deflate compression [ 2.747633] Platform Keyring initialized [ 2.861310] NET: Registered protocol family 38 [ 2.863476] Key type asymmetric registered [ 2.865115] Asymmetric key parser 'x509' registered [ 2.867342] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.870727] io scheduler mq-deadline registered [ 2.872631] io scheduler kyber registered [ 2.874714] io scheduler bfq registered [ 2.877347] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.880626] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.883903] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.886884] ACPI: Power Button [PWRF] [ 2.893191] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.901800] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.919650] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.949248] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.978109] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.984625] Non-volatile memory driver v1.3 [ 2.986624] Linux agpgart interface v0.103 [ 3.025525] virtio_blk virtio1: [vda] 146624 512-byte logical blocks (75.1 MB/71.6 MiB) [ 3.028658] vda: detected capacity change from 0 to 75071488 [ 3.045679] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.049148] vdb: detected capacity change from 0 to 1073741824 [ 3.067729] libphy: Fixed MDIO Bus: probed [ 3.074144] usbcore: registered new interface driver usbserial_generic [ 3.077121] usbserial: USB Serial support registered for generic [ 3.079911] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.084827] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.086347] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.088784] mousedev: PS/2 mouse device common for all mice [ 3.091842] rtc_cmos 00:05: RTC can wake from S4 [ 3.094531] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.095786] rtc_cmos 00:05: registered as rtc0 [ 3.101061] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.104383] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.106111] intel_pstate: CPU model not supported [ 3.112046] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.112675] hid: raw HID events driver (C) Jiri Kosina [ 3.117782] usbcore: registered new interface driver usbhid [ 3.120384] usbhid: USB HID core driver [ 3.122186] drop_monitor: Initializing network drop monitor service [ 3.124833] Initializing XFRM netlink socket [ 3.127130] NET: Registered protocol family 10 [ 3.131254] Segment Routing with IPv6 [ 3.133190] NET: Registered protocol family 17 [ 3.135796] mpls_gso: MPLS GSO support [ 3.142786] RAS: Correctable Errors collector initialized. [ 3.145163] AVX version of gcm_enc/dec engaged. [ 3.147440] AES CTR mode by8 optimization enabled [ 3.249910] sched_clock: Marking stable (3249873108, 0)->(4211414575, -961541467) [ 3.253600] registered taskstats version 1 [ 3.255635] Loading compiled-in X.509 certificates [ 3.257649] zswap: loaded using pool lzo/zbud [ 3.286536] Key type big_key registered [ 3.300498] Key type encrypted registered [ 3.303384] ima: No TPM chip found, activating TPM-bypass! [ 3.305883] ima: Allocated hash algorithm: sha1 [ 3.308158] ima: No architecture policies found [ 3.310561] evm: Initialising EVM extended attributes: [ 3.312752] evm: security.selinux [ 3.314199] evm: security.ima [ 3.315855] evm: security.capability [ 3.317816] evm: HMAC attrs: 0x1 [ 3.322705] rtc_cmos 00:05: setting system clock to 2026-08-28 02:20:45 UTC (1787883645) [ 3.329938] debug: unmapping init [mem 0xffffffffa1203000-0xffffffffa13fffff] [ 3.333585] debug: unmapping init [mem 0xffffffff9ff82000-0xffffffffa0258fff] [ 3.342127] Write protecting the kernel read-only data: 28672k [ 3.345350] debug: unmapping init [mem 0xffffffff9e603000-0xffffffff9e7fffff] [ 3.348387] debug: unmapping init [mem 0xffffffff9ef14000-0xffffffff9effffff] [ 3.383044] 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.392564] systemd[1]: Detected virtualization kvm. [ 3.394553] systemd[1]: Detected architecture x86-64. [ 3.396634] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.421276] systemd[1]: No hostname configured. [ 3.423171] systemd[1]: Set hostname to . [ 3.424992] random: systemd: uninitialized urandom read (16 bytes read) [ 3.428866] systemd[1]: Initializing machine ID from random generator. [ 3.579832] random: systemd: uninitialized urandom read (16 bytes read) [ 3.582702] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.586704] random: systemd: uninitialized urandom read (16 bytes read) [ 3.589908] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.596823] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Sockets. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.291414] device-mapper: uevent: version 1.0.3 [ 4.294167] 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. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.993252] virtio_net virtio0 ens2: renamed from eth0 [ 5.015389] scsi host0: ata_piix [ 5.032259] scsi host1: ata_piix [ 5.033866] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.036710] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.211091] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.824953] random: crng init done [ 9.826503] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.150490] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ 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 Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.288877] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.547110] SELinux: Disabled at runtime. [ 11.606916] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.616425] systemd[1]: Detected virtualization kvm. [ 11.618627] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.109115] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.113292] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.118977] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.123357] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.126668] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.136696] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.141842] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root a[ 12.212725] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS nd Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Control Socket. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.527098] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.865817] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.885651] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.979549] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.995812] EDAC sbridge: Ver: 1.1.2 [ 13.988812] Key type dns_resolver registered [ 14.292062] NFS: Registering the id_resolver key type [ 14.294405] Key type id_resolver registered [ 14.295957] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ 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 OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ 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 Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg411-client login: [ 73.272420] libcfs: loading out-of-tree module taints kernel. [ 73.415801] Key type ._llcrypt registered [ 73.418466] Key type .llcrypt registered [ 74.494557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 74.524182] alg: No test for adler32 (adler32-zlib) [ 76.167489] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 77.193624] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 79.040681] Key type lgssc registered [ 81.278835] Lustre: Echo OBD driver; http://www.lustre.org/ [ 112.308321] hrtimer: interrupt took 3115763 ns [ 274.324277] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 279.462922] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 292.708779] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing check_logdir /tmp/testlogs/ [ 298.209271] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing yml_node [ 300.006502] Lustre: lustre-OST0000-osc-ffff8b4a904ed000: disconnect after 24s idle [ 302.997459] Lustre: DEBUG MARKER: Client: 2.17.57.84 [ 306.322862] Lustre: DEBUG MARKER: MDS: 2.17.57.84 [ 310.663672] Lustre: DEBUG MARKER: OSS: 2.17.57.84 [ 313.111169] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Thu Aug 27 22:25:53 EDT 2026 [ 331.021748] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 332.713952] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 335.224087] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 345.567019] Lustre: Unmounted lustre-client [ 457.167751] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 463.059564] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 487.772673] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 525.361790] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 22:29:26 (1787884166) [ 528.072734] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 529.993801] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 22:29:30 (1787884170) [ 572.649243] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 578.582203] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 601.173481] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 612.474426] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 617.394399] Lustre: Unmounted lustre-client [ 649.641584] Key type lgssc unregistered [ 650.002967] LNet: 15054:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 650.014109] LNetError: 15054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 650.032845] LNet: Removed LNI 192.168.204.11@tcp [ 650.834181] Key type .llcrypt unregistered [ 650.841068] Key type ._llcrypt unregistered [ 669.008385] Key type ._llcrypt registered [ 669.010768] Key type .llcrypt registered [ 670.374350] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 22:31:51 (1787884311) [ 672.642709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 672.675865] alg: No test for adler32 (adler32-zlib) [ 673.907084] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 674.275622] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 676.072926] Key type lgssc registered [ 677.458321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 759.501132] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 845.069919] Lustre: Unmounted lustre-client [ 876.938442] Key type lgssc unregistered [ 877.222889] LNet: 18834:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 877.230044] LNetError: 18834:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 877.256716] LNet: Removed LNI 192.168.204.11@tcp [ 878.187076] Key type .llcrypt unregistered [ 878.189444] Key type ._llcrypt unregistered [ 888.870747] Key type ._llcrypt registered [ 888.873579] Key type .llcrypt registered [ 890.883500] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 22:35:30 (1787884530) [ 894.281389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 894.397602] alg: No test for adler32 (adler32-zlib) [ 895.558734] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 895.930040] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 897.624360] Key type lgssc registered [ 899.232887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 949.066888] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 956.001477] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 983.330388] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 985.095673] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 994.822455] Lustre: Unmounted lustre-client [ 1030.840786] Key type lgssc unregistered [ 1031.103275] LNet: 22431:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1031.115815] LNetError: 22431:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1031.147166] LNet: Removed LNI 192.168.204.11@tcp [ 1031.889762] Key type .llcrypt unregistered [ 1031.893170] Key type ._llcrypt unregistered [ 1042.890748] Key type ._llcrypt registered [ 1042.893936] Key type .llcrypt registered [ 1044.283976] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 22:38:04 (1787884684) [ 1046.378284] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1046.566620] alg: No test for adler32 (adler32-zlib) [ 1047.643134] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 1047.852310] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 1049.536183] Key type lgssc registered [ 1050.492657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1105.352470] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1111.118931] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1137.511231] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1140.621981] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 1147.186594] Lustre: Unmounted lustre-client [ 1177.558807] Key type lgssc unregistered [ 1177.878610] LNet: 25615:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1177.886276] LNetError: 25615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1177.909546] LNet: Removed LNI 192.168.204.11@tcp [ 1178.649742] Key type .llcrypt unregistered [ 1178.657666] Key type ._llcrypt unregistered [ 1196.587542] Key type ._llcrypt registered [ 1196.590291] Key type .llcrypt registered [ 1198.075943] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 22:40:38 (1787884838) [ 1200.833170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1200.878929] alg: No test for adler32 (adler32-zlib) [ 1202.031298] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 1202.346988] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 1204.048207] Key type lgssc registered [ 1205.279566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1264.598362] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1271.416555] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1297.355950] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1303.625551] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 1308.663789] Lustre: lustre-MDT0000-mdc-ffff8b4a82bcd000: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1318.911230] Lustre: lustre-MDT0001-mdc-ffff8b4a82bcd000: Connection to lustre-MDT0001 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1323.749274] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1329.120573] Lustre: 26572:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787884955/real 1787884955] req@00000000faa6c726 x1874733138187904/t0(0) o400->MGC192.168.204.111@tcp@192.168.204.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1787884971 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1329.152866] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [ 1334.116078] Lustre: Unmounted lustre-client [ 1355.002946] Key type lgssc unregistered [ 1355.271900] LNet: 29264:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1355.285521] LNetError: 29264:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1355.320346] LNet: Removed LNI 192.168.204.11@tcp [ 1356.090920] Key type .llcrypt unregistered [ 1356.093397] Key type ._llcrypt unregistered [ 1371.352049] Key type ._llcrypt registered [ 1371.358788] Key type .llcrypt registered [ 1372.705704] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 22:43:33 (1787885013) [ 1376.126236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1376.140929] alg: No test for adler32 (adler32-zlib) [ 1377.264534] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 1377.578310] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 1379.292591] Key type lgssc registered [ 1380.477507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1435.919846] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1444.189973] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1471.962883] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1478.205199] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 1483.245675] Lustre: lustre-OST0000-osc-ffff8b4a92e00800: Connection to lustre-OST0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1490.795216] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1501.201195] Lustre: Unmounted lustre-client [ 1537.930744] Key type lgssc unregistered [ 1538.382287] LNet: 32874:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1538.394420] LNetError: 32874:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1538.422660] LNet: Removed LNI 192.168.204.11@tcp [ 1539.446189] Key type .llcrypt unregistered [ 1539.455667] Key type ._llcrypt unregistered [ 1556.070753] Key type ._llcrypt registered [ 1556.073165] Key type .llcrypt registered [ 1557.590953] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 22:46:38 (1787885198) [ 1561.164486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1561.371653] alg: No test for adler32 (adler32-zlib) [ 1562.834402] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 1563.300151] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 1565.112390] Key type lgssc registered [ 1566.573729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1621.276233] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1627.653551] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1651.892115] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1654.943622] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 1658.486593] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1662.589072] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1674.079658] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-2722h48m34s [ 1676.355584] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1680.366815] Lustre: lustre-MDT0000-mdc-ffff8b4a87953000: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1687.621132] LustreError: 36662:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8b4a87953000: can't stat MDS #0: rc = -19 [ 1689.208720] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1698.801606] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [ 1698.812928] LustreError: lustre-MDT0000-mdc-ffff8b4a87953000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1698.841175] Lustre: lustre-MDT0000-mdc-ffff8b4a87953000: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 1698.847282] Lustre: Evicted from MGS (at 192.168.204.111@tcp) after server handle changed from 0xdb246f1934d7f583 to 0xdb246f1934d7f831 [ 1707.322690] Lustre: DEBUG MARKER: Wait for df (36662) ... [ 1708.914261] Lustre: DEBUG MARKER: done [ 1711.203828] Lustre: Unmounted lustre-client [ 1739.517336] Key type lgssc unregistered [ 1739.850260] LNet: 37675:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1739.857152] LNetError: 37675:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1739.880133] LNet: Removed LNI 192.168.204.11@tcp [ 1740.704644] Key type .llcrypt unregistered [ 1740.711425] Key type ._llcrypt unregistered [ 1772.426187] Key type ._llcrypt registered [ 1772.427899] Key type .llcrypt registered [ 1773.822486] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 22:50:14 (1787885414) [ 1776.688039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1776.768354] alg: No test for adler32 (adler32-zlib) [ 1778.011267] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 1778.395175] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 1780.144362] Key type lgssc registered [ 1781.906208] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1841.296842] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1848.371466] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1870.889685] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1873.903609] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 1879.138661] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-2722h51m59s [ 1880.772207] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1888.629272] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1891.473926] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1911.776466] Lustre: 41814:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787885533/real 1787885533] req@00000000ac7eaf68 x1874733742168832/t0(0) o101->lustre-MDT0000-mdc-ffff8b4a87879800@192.168.204.111@tcp:12/10 lens 576/1152 e 0 to 1 dl 1787885553 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1911.804119] Lustre: lustre-MDT0000-mdc-ffff8b4a87879800: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1911.858078] Lustre: lustre-MDT0000-mdc-ffff8b4a87879800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 1933.699956] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1937.884201] Lustre: DEBUG MARKER: done [ 1943.246103] Lustre: Unmounted lustre-client [ 1983.106324] Key type lgssc unregistered [ 1983.494065] LNet: 42701:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1983.506554] LNetError: 42701:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1983.532737] LNet: Removed LNI 192.168.204.11@tcp [ 1984.707309] Key type .llcrypt unregistered [ 1984.714264] Key type ._llcrypt unregistered [ 2018.527328] Key type ._llcrypt registered [ 2018.531048] Key type .llcrypt registered [ 2020.104207] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 22:54:20 (1787885660) [ 2021.676533] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 2023.482395] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 22:54:24 (1787885664) [ 2028.360676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2028.381360] alg: No test for adler32 (adler32-zlib) [ 2029.679807] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 2029.994549] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 2031.776250] Key type lgssc registered [ 2033.102447] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2061.537326] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 22:55:01 (1787885701) [ 2099.975748] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2106.384665] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2127.227500] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2128.973759] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 2132.159615] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 2135.959814] Lustre: Unmounted lustre-client [ 2151.628256] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 2201.198611] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2208.260052] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2208.804710] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 2213.102349] Lustre: Unmounted lustre-client [ 2278.375804] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2284.262161] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2284.832604] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 2289.322293] Lustre: Unmounted lustre-client [ 2311.260378] Lustre: DEBUG MARKER: files compared the same [ 2334.864902] Key type lgssc unregistered [ 2335.099353] LNet: 50319:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2335.104978] LNetError: 50319:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2335.122799] LNet: Removed LNI 192.168.204.11@tcp [ 2335.818954] Key type .llcrypt unregistered [ 2335.821804] Key type ._llcrypt unregistered [ 2351.623303] Key type ._llcrypt registered [ 2351.627296] Key type .llcrypt registered [ 2352.954794] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 22:59:53 (1787885993) [ 2355.589991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2355.621764] alg: No test for adler32 (adler32-zlib) [ 2356.706923] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 2357.058977] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 2358.704230] Key type lgssc registered [ 2360.051152] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2419.757977] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2427.196313] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2453.179751] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2460.218354] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 2464.123884] Lustre: Unmounted lustre-client [ 2499.903844] Key type lgssc unregistered [ 2500.104244] LNet: 53914:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2500.109421] LNetError: 53914:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2500.128553] LNet: Removed LNI 192.168.204.11@tcp [ 2500.631387] Key type .llcrypt unregistered [ 2500.634813] Key type ._llcrypt unregistered [ 2516.353426] Key type ._llcrypt registered [ 2516.357174] Key type .llcrypt registered [ 2517.649811] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 23:02:38 (1787886158) [ 2520.125677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2520.145384] alg: No test for adler32 (adler32-zlib) [ 2521.351449] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 2521.581493] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 2523.272804] Key type lgssc registered [ 2524.485357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2610.431644] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2650.209264] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2658.013552] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2685.777243] Key type lgssc unregistered [ 2685.977724] LNet: 57398:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2685.998761] LNetError: 57398:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2686.018748] LNet: Removed LNI 192.168.204.11@tcp [ 2687.007619] Key type .llcrypt unregistered [ 2687.012239] Key type ._llcrypt unregistered [ 2708.169202] Key type ._llcrypt registered [ 2708.172613] Key type .llcrypt registered [ 2710.038106] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 23:05:50 (1787886350) [ 2714.074531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2714.100800] alg: No test for adler32 (adler32-zlib) [ 2715.366579] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 2715.719261] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 2717.488951] Key type lgssc registered [ 2719.112519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2809.842832] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 2818.270708] Lustre: Unmounted lustre-client [ 2858.668737] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 23:08:18 (1787886498) [ 3000.851359] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3024.483207] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3032.343467] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3032.356360] LustreError: 63880:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8b4a87c5e800: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 3032.396645] Lustre: 58379:0:(llite_lib.c:4342:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.111@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 3042.761273] Lustre: Unmounted lustre-client [ 3108.313614] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 23:12:28 (1787886748) [ 3120.985591] Key type lgssc unregistered [ 3121.255991] LNet: 65094:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3121.263592] LNetError: 65094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3121.292244] LNet: Removed LNI 192.168.204.11@tcp [ 3122.004807] Key type .llcrypt unregistered [ 3122.011051] Key type ._llcrypt unregistered [ 3134.423579] Key type ._llcrypt registered [ 3134.426481] Key type .llcrypt registered [ 3134.951397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3134.968880] alg: No test for adler32 (adler32-zlib) [ 3136.221857] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 3136.621561] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 3138.312337] Key type lgssc registered [ 3139.711399] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3197.095827] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3203.525029] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3225.979870] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3227.721765] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3235.506907] Lustre: Unmounted lustre-client [ 3263.807648] Key type lgssc unregistered [ 3264.049404] LNet: 68108:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3264.058327] LNetError: 68108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3264.096230] LNet: Removed LNI 192.168.204.11@tcp [ 3264.834461] Key type .llcrypt unregistered [ 3264.837232] Key type ._llcrypt unregistered [ 3277.385070] Key type ._llcrypt registered [ 3277.389459] Key type .llcrypt registered [ 3277.743069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3277.752606] alg: No test for adler32 (adler32-zlib) [ 3278.895993] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 3279.309945] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 3281.096789] Key type lgssc registered [ 3282.672702] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3406.546879] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3413.778853] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3438.172985] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3440.117824] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3443.208940] Lustre: Unmounted lustre-client [ 3471.266799] Key type lgssc unregistered [ 3471.567412] LNet: 71540:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3471.584182] LNetError: 71540:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3471.619132] LNet: Removed LNI 192.168.204.11@tcp [ 3472.277707] Key type .llcrypt unregistered [ 3472.288069] Key type ._llcrypt unregistered [ 3489.006935] Key type ._llcrypt registered [ 3489.009210] Key type .llcrypt registered [ 3490.273146] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 23:18:50 (1787887130) [ 3492.469613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3492.563568] alg: No test for adler32 (adler32-zlib) [ 3493.577016] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 3493.767970] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 3495.442171] Key type lgssc registered [ 3497.170940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3553.704167] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3563.394542] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3591.503130] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3598.421691] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3604.877824] Lustre: Unmounted lustre-client [ 3605.377110] LustreError: 72494:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [ 3605.447895] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3609.115604] Lustre: Unmounted lustre-client [ 3654.161114] Key type lgssc unregistered [ 3654.421680] LNet: 75230:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3654.444586] LNetError: 75230:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3654.460344] LNet: Removed LNI 192.168.204.11@tcp [ 3655.164869] Key type .llcrypt unregistered [ 3655.167841] Key type ._llcrypt unregistered [ 3667.208425] Key type ._llcrypt registered [ 3667.214764] Key type .llcrypt registered [ 3667.820677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3667.835713] alg: No test for adler32 (adler32-zlib) [ 3668.870853] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 3669.120084] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 3670.768561] Key type lgssc registered [ 3672.023887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3726.050938] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3731.403165] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3754.314466] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3756.384916] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3765.702145] Lustre: Unmounted lustre-client [ 3794.041428] Key type lgssc unregistered [ 3794.346243] LNet: 78263:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3794.359801] LNetError: 78263:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3794.379712] LNet: Removed LNI 192.168.204.11@tcp [ 3795.074605] Key type .llcrypt unregistered [ 3795.078363] Key type ._llcrypt unregistered [ 3809.819833] Key type ._llcrypt registered [ 3809.826028] Key type .llcrypt registered [ 3811.089045] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 23:24:11 (1787887451) [ 3813.058292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3813.070971] alg: No test for adler32 (adler32-zlib) [ 3814.131531] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 3814.425742] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 3816.128247] Key type lgssc registered [ 3817.161965] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3867.710495] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3873.776368] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3895.080219] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3896.848372] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 3910.187678] Lustre: lustre: root_squash is set to 0:0 [ 3910.219957] Lustre: lustre: nosquash_nids is cleared [ 3916.754116] Lustre: lustre: root_squash is set to 500:500 [ 3925.877969] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.204.11@tcp 192.168.0.[2,10]@tcp [ 3935.869795] Lustre: lustre: nosquash_nids set to 192.168.204.11/24@tcp [ 3943.825610] Lustre: Unmounted lustre-client [ 3976.412861] Key type lgssc unregistered [ 3976.861447] LNet: 82207:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3976.874816] LNetError: 82207:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3976.892322] LNet: Removed LNI 192.168.204.11@tcp [ 3977.854902] Key type .llcrypt unregistered [ 3977.860390] Key type ._llcrypt unregistered [ 3998.905274] Key type ._llcrypt registered [ 3998.907546] Key type .llcrypt registered [ 4000.859841] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 23:27:20 (1787887640) [ 4004.290036] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 4006.715367] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 23:27:26 (1787887646) [ 4009.802093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4009.852334] alg: No test for adler32 (adler32-zlib) [ 4010.875525] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 4011.086655] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 4012.792312] Key type lgssc registered [ 4013.999086] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4071.254235] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4078.219120] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4101.984289] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4103.588242] Lustre: lustre: root_squash is set to 500:500 [ 4103.639696] Lustre: lustre: nosquash_nids set to 192.168.204.11/24@tcp [ 4114.021950] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 4121.334426] Lustre: Unmounted lustre-client [ 4150.778795] Key type lgssc unregistered [ 4151.108686] LNet: 86167:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4151.116525] LNetError: 86167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4151.144724] LNet: Removed LNI 192.168.204.11@tcp [ 4151.853150] Key type .llcrypt unregistered [ 4151.854628] Key type ._llcrypt unregistered [ 4170.267434] Key type ._llcrypt registered [ 4170.269858] Key type .llcrypt registered [ 4172.245348] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 4174.398087] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 23:30:14 (1787887814) [ 4193.439029] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4193.487843] alg: No test for adler32 (adler32-zlib) [ 4194.569877] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 4194.774651] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 4196.504373] Key type lgssc registered [ 4197.726865] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4285.600342] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4292.428176] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4319.345547] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4411.730147] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4418.054665] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4446.550873] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4460.393045] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 4499.694965] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b4aa2bbe800.ost_server_uuid 50 [ 4501.133986] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b4aa2bbe800.ost_server_uuid in IDLE state after 0 sec [ 4501.635857] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 4506.261881] Lustre: Unmounted lustre-client [ 4508.361838] Lustre: Unmounted lustre-client [ 4551.253943] Key type lgssc unregistered [ 4551.574516] LNet: 93833:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4551.584602] LNetError: 93833:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4551.602510] LNet: Removed LNI 192.168.204.11@tcp [ 4552.342698] Key type .llcrypt unregistered [ 4552.347403] Key type ._llcrypt unregistered [ 4584.027980] Key type ._llcrypt registered [ 4584.033194] Key type .llcrypt registered [ 4586.041942] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 23:37:05 (1787888225) [ 4606.297735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4606.517474] alg: No test for adler32 (adler32-zlib) [ 4607.859865] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 4608.121531] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 4609.816407] Key type lgssc registered [ 4611.178080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4698.401436] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4705.266506] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4731.170182] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4732.839808] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 4754.400819] Lustre: 95194:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787888380/real 1787888380] req@00000000245b3ae4 x1874736709637248/t0(0) o400->lustre-OST0000-osc-ffff8b4a8789f800@192.168.204.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1787888396 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4754.457261] Lustre: lustre-OST0000-osc-ffff8b4a8789f800: Connection to lustre-OST0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4759.563411] Lustre: 95194:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787888385/real 1787888385] req@0000000009349526 x1874736709637760/t0(0) o400->lustre-OST0000-osc-ffff8b4a8789f800@192.168.204.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1787888401 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4764.715767] Lustre: lustre-OST0000-osc-ffff8b4a8789f800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 4765.792243] Lustre: 95194:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787888390/real 1787888390] req@00000000767478a3 x1874736709638272/t0(0) o400->lustre-OST0000-osc-ffff8b4a8789f800@192.168.204.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1787888406 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4768.929347] Lustre: 95194:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787888395/real 1787888395] req@000000008eb190db x1874736709638784/t0(0) o400->lustre-OST0000-osc-ffff8b4a8789f800@192.168.204.111@tcp:28/4 lens 224/224 e 0 to 1 dl 1787888411 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4769.776902] Lustre: lustre-MDT0000-mdc-ffff8b4a8789f800: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4785.120368] Lustre: 95194:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787888411/real 1787888411] req@00000000b2f69f5b x1874736709641088/t0(0) o400->MGC192.168.204.111@tcp@192.168.204.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1787888427 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4785.154267] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [ 4795.390618] Lustre: Evicted from MGS (at 192.168.204.111@tcp) after server handle changed from 0x7147bbc7781d9211 to 0x7147bbc7781d94a3 [ 4795.410542] Lustre: MGC192.168.204.111@tcp: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 4800.368155] Lustre: lustre-MDT0000-mdc-ffff8b4a8789f800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 4806.336506] Lustre: Unmounted lustre-client [ 4842.478816] Key type lgssc unregistered [ 4842.777250] LNet: 99493:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4842.781936] LNetError: 99493:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4843.818455] LNet: Removed LNI 192.168.204.11@tcp [ 4844.619478] Key type .llcrypt unregistered [ 4844.623945] Key type ._llcrypt unregistered [ 4864.375614] Key type ._llcrypt registered [ 4864.384847] Key type .llcrypt registered [ 4865.805699] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 23:41:46 (1787888506) [ 4880.796690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4880.912139] alg: No test for adler32 (adler32-zlib) [ 4882.071203] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 4882.381250] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 4884.075215] Key type lgssc registered [ 4885.399489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4923.449244] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5033.287889] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5040.760613] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5067.822113] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5070.598411] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 5073.315103] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 5075.667595] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 23:45:15 (1787888715) [ 5342.156506] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5352.549339] Lustre: Unmounted lustre-client [ 5478.530701] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5484.865222] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5513.791324] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5559.046661] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:53:19 (1787889199) [ 5657.649761] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5664.732406] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5691.573210] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5692.312113] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 5697.766920] Lustre: Unmounted lustre-client [ 5728.527292] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 23:56:09 (1787889369) [ 5833.082596] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5839.989408] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5866.933872] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5867.829255] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 5872.573139] Lustre: Unmounted lustre-client [ 5900.334349] Key type lgssc unregistered [ 5900.740649] LNet: 122529:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5900.754651] LNetError: 122529:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5900.772790] LNet: Removed LNI 192.168.204.11@tcp [ 5901.630661] Key type .llcrypt unregistered [ 5901.632751] Key type ._llcrypt unregistered [ 5918.520907] Key type ._llcrypt registered [ 5918.523131] Key type .llcrypt registered [ 5920.110119] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 23:59:20 (1787889560) [ 5922.808314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5922.891610] alg: No test for adler32 (adler32-zlib) [ 5924.097466] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 5924.390626] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 5926.136723] Key type lgssc registered [ 5927.856774] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5984.512681] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5991.555599] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6014.834357] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6026.803942] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 6039.855424] Lustre: Unmounted lustre-client [ 6074.980509] Key type lgssc unregistered [ 6075.248864] LNet: 126142:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6075.264517] LNetError: 126142:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6075.289763] LNet: Removed LNI 192.168.204.11@tcp [ 6075.993939] Key type .llcrypt unregistered [ 6075.996667] Key type ._llcrypt unregistered [ 6089.671312] Key type ._llcrypt registered [ 6089.675917] Key type .llcrypt registered [ 6090.847586] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 00:02:11 (1787889731) [ 6093.497438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6093.565552] alg: No test for adler32 (adler32-zlib) [ 6094.719812] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 6095.006470] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 6096.752208] Key type lgssc registered [ 6097.942332] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6149.659205] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6156.066171] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6178.414894] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6181.080996] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 6191.617897] Lustre: lustre-OST0000-osc-ffff8b4a87898000: Connection to lustre-OST0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6202.614276] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8b4a87898000.ost_server_uuid 50 [ 6204.568728] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 6215.997865] Lustre: Unmounted lustre-client [ 6238.325432] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 00:04:38 (1787889878) [ 6270.700217] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6276.641601] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6298.675128] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6324.621194] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6325.272589] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 6330.354087] Lustre: lustre-OST0000-osc-ffff8b4a87879800: Connection to lustre-OST0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6357.111757] Lustre: Unmounted lustre-client [ 6407.144592] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 00:07:28 (1787890048) [ 6444.002723] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6451.108707] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6477.008886] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6500.811203] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6501.400509] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 6506.485240] Lustre: lustre-OST0000-osc-ffff8b4a8787e800: Connection to lustre-OST0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6525.921027] Lustre: Unmounted lustre-client [ 6577.339616] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 00:10:17 (1787890217) [ 6684.938208] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6691.853277] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6719.214619] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6799.248302] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6805.894907] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6829.398611] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6863.498392] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 6911.663988] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6914.003767] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6917.257838] Lustre: Unmounted lustre-client [ 6953.387437] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 00:16:33 (1787890593) [ 6989.917700] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6995.570285] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7017.815509] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7055.542467] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7090.025289] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7136.473771] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7138.372600] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7150.827669] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7161.183289] Lustre: Unmounted lustre-client [ 7219.230942] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 00:21:00 (1787890860) [ 7255.799704] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7261.794542] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7284.320172] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7284.778330] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7311.460512] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7313.125909] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7331.236754] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8b4a911a9800.ost_server_uuid 50 [ 7332.705744] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8b4a911a9800.ost_server_uuid in IDLE state after 0 sec [ 7336.016162] Lustre: Unmounted lustre-client [ 7336.397977] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7336.410046] Lustre: Skipped 3 previous similar messages [ 7336.555205] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7337.721208] LustreError: 149582:0:(sec.c:403:import_sec_validate_get()) import 000000008a62df22 (NEW) with no sec [ 7400.679876] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 00:24:01 (1787891041) [ 7435.322093] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7441.713252] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7465.029743] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7487.872550] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7488.465471] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 7488.619870] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7499.791362] Lustre: Unmounted lustre-client [ 7499.795348] Lustre: Skipped 1 previous similar message [ 7547.127365] Key type lgssc unregistered [ 7547.477382] LNet: 152992:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7547.491262] LNetError: 152992:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7547.530178] LNet: Removed LNI 192.168.204.11@tcp [ 7548.333095] Key type .llcrypt unregistered [ 7548.335349] Key type ._llcrypt unregistered [ 7563.579588] Key type ._llcrypt registered [ 7563.581622] Key type .llcrypt registered [ 7564.832871] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 00:26:45 (1787891205) [ 7565.678508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7565.687940] alg: No test for adler32 (adler32-zlib) [ 7566.822872] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 7567.071140] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 7568.792555] Key type lgssc registered [ 7569.990489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7629.886761] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7636.449853] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7659.463986] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7682.523454] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7683.170661] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7684.325481] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7688.235344] LustreError: lustre-MDT0001-mdc-ffff8b4a87f44800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 7688.270742] Lustre: lustre-MDT0001-mdc-ffff8b4a87f44800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [ 7695.738339] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7697.682761] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 7723.551965] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 7733.429210] Lustre: Unmounted lustre-client [ 7763.127119] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 00:30:03 (1787891403) [ 7865.225369] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7871.812443] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7895.673269] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7896.311124] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 7924.210311] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7925.743679] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7937.543603] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 7947.932248] Lustre: Unmounted lustre-client [ 7984.392827] Key type lgssc unregistered [ 7984.738117] LNet: 162597:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7984.755982] LNetError: 162597:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7984.796026] LNet: Removed LNI 192.168.204.11@tcp [ 7985.644648] Key type .llcrypt unregistered [ 7985.647439] Key type ._llcrypt unregistered [ 8019.617439] Key type ._llcrypt registered [ 8019.620593] Key type .llcrypt registered [ 8021.068082] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 00:34:21 (1787891661) [ 8023.425469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8023.451649] alg: No test for adler32 (adler32-zlib) [ 8024.596521] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 8024.838593] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 8026.592224] Key type lgssc registered [ 8027.860059] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8086.009562] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8091.790796] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8116.849966] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8119.640073] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8167.634249] Lustre: Unmounted lustre-client [ 8209.623288] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8210.495287] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8246.558518] Lustre: Unmounted lustre-client [ 8285.307255] Key type lgssc unregistered [ 8285.582546] LNet: 167042:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8285.589358] LNetError: 167042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8285.612590] LNet: Removed LNI 192.168.204.11@tcp [ 8286.662258] Key type .llcrypt unregistered [ 8286.666379] Key type ._llcrypt unregistered [ 8304.573333] Key type ._llcrypt registered [ 8304.576780] Key type .llcrypt registered [ 8306.402296] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 00:39:06 (1787891946) [ 8309.367130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8309.401706] alg: No test for adler32 (adler32-zlib) [ 8310.587368] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 8310.874058] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 8312.656214] Key type lgssc registered [ 8313.938314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8371.165718] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8377.830456] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8401.871785] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8403.667523] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8424.315628] Lustre: Unmounted lustre-client [ 8453.944707] Key type lgssc unregistered [ 8454.295923] LNet: 170801:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8454.310846] LNetError: 170801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8454.334251] LNet: Removed LNI 192.168.204.11@tcp [ 8455.096273] Key type .llcrypt unregistered [ 8455.108223] Key type ._llcrypt unregistered [ 8468.617488] Key type ._llcrypt registered [ 8468.622411] Key type .llcrypt registered [ 8469.213169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8469.229628] alg: No test for adler32 (adler32-zlib) [ 8470.434701] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 8470.753521] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 8472.472274] Key type lgssc registered [ 8474.033234] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8530.208747] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8536.056395] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8561.713582] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8563.761837] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8572.473770] Lustre: Unmounted lustre-client [ 8619.157914] Key type lgssc unregistered [ 8619.512987] LNet: 173861:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8619.519551] LNetError: 173861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8619.540697] LNet: Removed LNI 192.168.204.11@tcp [ 8620.173286] Key type .llcrypt unregistered [ 8620.180075] Key type ._llcrypt unregistered [ 8638.292973] Key type ._llcrypt registered [ 8638.295352] Key type .llcrypt registered [ 8640.115591] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 00:44:40 (1787892280) [ 8643.157304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8643.205173] alg: No test for adler32 (adler32-zlib) [ 8644.499458] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 8644.950420] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 8646.752625] Key type lgssc registered [ 8647.971579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8705.136905] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8712.382770] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8738.865054] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8750.129594] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8772.067223] Lustre: Unmounted lustre-client [ 8802.767552] Key type lgssc unregistered [ 8803.232746] LNet: 177615:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8803.245729] LNetError: 177615:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8803.291461] LNet: Removed LNI 192.168.204.11@tcp [ 8804.111238] Key type .llcrypt unregistered [ 8804.112985] Key type ._llcrypt unregistered [ 8821.586058] Key type ._llcrypt registered [ 8821.587991] Key type .llcrypt registered [ 8821.985833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8822.000617] alg: No test for adler32 (adler32-zlib) [ 8823.090733] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 8823.366259] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 8825.088218] Key type lgssc registered [ 8826.428264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8884.957410] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8890.469800] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8914.809843] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8917.684527] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 8925.573121] Lustre: Unmounted lustre-client [ 8955.965488] Key type lgssc unregistered [ 8956.279245] LNet: 180679:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8956.295546] LNetError: 180679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8956.322947] LNet: Removed LNI 192.168.204.11@tcp [ 8957.171724] Key type .llcrypt unregistered [ 8957.175325] Key type ._llcrypt unregistered [ 8975.327593] Key type ._llcrypt registered [ 8975.330419] Key type .llcrypt registered [ 8976.753624] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 00:50:17 (1787892617) [ 8999.986789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9000.051903] alg: No test for adler32 (adler32-zlib) [ 9001.124972] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 9001.393752] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 9003.072838] Key type lgssc registered [ 9004.349792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9099.960077] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9107.214050] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9133.751746] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9178.900340] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 00:53:39 (1787892819) [ 9218.160191] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9223.514589] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9247.960301] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9251.425401] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 9258.152415] Lustre: Unmounted lustre-client [ 9286.971941] Key type lgssc unregistered [ 9287.214275] LNet: 187773:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9287.223551] LNetError: 187773:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9287.267436] LNet: Removed LNI 192.168.204.11@tcp [ 9288.105878] Key type .llcrypt unregistered [ 9288.109676] Key type ._llcrypt unregistered [ 9303.314182] Key type ._llcrypt registered [ 9303.317551] Key type .llcrypt registered [ 9304.511976] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 9306.684627] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 00:55:47 (1787892947) [ 9326.169612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9326.213163] alg: No test for adler32 (adler32-zlib) [ 9327.292505] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 9327.497786] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 9329.201602] Key type lgssc registered [ 9330.373676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9426.328966] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9432.183041] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9454.035251] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9475.044501] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9476.839114] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [ 9500.027398] Lustre: Unmounted lustre-client [ 9619.069696] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9625.726098] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9649.127026] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9688.435215] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 01:02:09 (1787893329) [ 9690.251537] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 9692.428633] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 01:02:12 (1787893332) [ 9789.885797] Key type lgssc unregistered [ 9790.211379] LNet: 199226:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9790.226333] LNetError: 199226:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9790.251086] LNet: Removed LNI 192.168.204.11@tcp [ 9791.095586] Key type .llcrypt unregistered [ 9791.100485] Key type ._llcrypt unregistered [ 9808.531448] Key type ._llcrypt registered [ 9808.538325] Key type .llcrypt registered [ 9810.150444] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 01:04:10 (1787893450) [ 9853.931066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9853.952675] alg: No test for adler32 (adler32-zlib) [ 9855.027782] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 9855.223658] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [ 9856.952423] Key type lgssc registered [ 9857.913112] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9904.460547] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9911.987398] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9948.637491] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9977.097470] Key type lgssc unregistered [ 9977.315268] LNet: 202952:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9977.326861] LNetError: 202952:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9977.343241] LNet: Removed LNI 192.168.204.11@tcp [ 9978.047705] Key type .llcrypt unregistered [ 9978.049278] Key type ._llcrypt unregistered [ 9994.098385] Key type ._llcrypt registered [ 9994.100864] Key type .llcrypt registered [ 9995.396639] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 01:07:16 (1787893636) [ 9997.773188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9997.856026] alg: No test for adler32 (adler32-zlib) [ 9999.019200] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [ 9999.317648] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [10000.984982] Key type lgssc registered [10002.156485] Lustre: Echo OBD driver; http://www.lustre.org/ [10057.347310] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10063.900772] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10088.802908] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10100.826122] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [10110.958973] Lustre: lustre-MDT0000-mdc-ffff8b4a8789b000: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10121.198278] Lustre: lustre-MDT0001-mdc-ffff8b4a8789b000: Connection to lustre-MDT0001 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10132.448294] Lustre: 203942:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787893758/real 1787893758] req@00000000020e93e9 x1874742362587776/t0(0) o400->MGC192.168.204.111@tcp@192.168.204.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1787893774 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10132.490706] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [10142.765342] Lustre: Evicted from MGS (at 192.168.204.111@tcp) after server handle changed from 0xc5cceed7aa87b334 to 0xc5cceed7aa87ce00 [10142.784690] Lustre: MGC192.168.204.111@tcp: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10166.259516] LustreError: lustre-MDT0000-mdc-ffff8b4a8789b000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [10166.308818] Lustre: lustre-MDT0000-mdc-ffff8b4a8789b000: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10172.079530] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10174.281379] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10180.315424] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10182.351488] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [10186.435145] Lustre: Unmounted lustre-client [10224.027924] Key type lgssc unregistered [10224.472502] LNet: 207895:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10224.495907] LNetError: 207895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10224.516618] LNet: Removed LNI 192.168.204.11@tcp [10225.787808] Key type .llcrypt unregistered [10225.795591] Key type ._llcrypt unregistered [10244.250289] Key type ._llcrypt registered [10244.262692] Key type .llcrypt registered [10245.948482] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 01:11:26 (1787893886) [10248.618086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10248.676035] alg: No test for adler32 (adler32-zlib) [10249.730552] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [10249.973727] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [10251.640249] Key type lgssc registered [10253.037061] Lustre: Echo OBD driver; http://www.lustre.org/ [10306.767179] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10312.856076] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10372.728930] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10379.789619] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10421.663838] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10447.971812] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10490.750048] Key type lgssc unregistered [10491.054097] LNet: 213189:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10491.059939] LNetError: 213189:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10491.081319] LNet: Removed LNI 192.168.204.11@tcp [10491.875765] Key type .llcrypt unregistered [10491.880211] Key type ._llcrypt unregistered [10539.247759] Key type ._llcrypt registered [10539.252651] Key type .llcrypt registered [10540.975247] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 01:16:21 (1787894181) [10584.432683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10584.480556] alg: No test for adler32 (adler32-zlib) [10585.712785] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [10586.068964] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [10587.888312] Key type lgssc registered [10589.626339] Lustre: Echo OBD driver; http://www.lustre.org/ [10685.013554] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10691.560960] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10720.183359] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10764.352905] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 01:20:05 (1787894405) [10773.442726] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 01:20:13 (1787894413) [10815.077108] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10821.471239] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10846.162916] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10852.416740] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [10859.033224] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [10861.706795] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [10863.783962] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [10865.891922] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [10872.826181] Lustre: lustre-MDT0000-mdc-ffff8b4a904ed800: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10888.160228] Lustre: 215086:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787894514/real 1787894514] req@00000000da55d25d x1874742978029568/t0(0) o400->MGC192.168.204.111@tcp@192.168.204.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1787894530 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10888.174629] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [10888.218503] Lustre: Evicted from MGS (at 192.168.204.111@tcp) after server handle changed from 0x13f519960d947566 to 0x13f519960d947742 [10888.226418] Lustre: MGC192.168.204.111@tcp: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10893.700442] Lustre: lustre-MDT0000-mdc-ffff8b4a904ed800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10902.178972] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10904.906744] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10908.973508] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [10913.801177] Lustre: lustre-MDT0000-mdc-ffff8b4a904ed800: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [10929.121032] Lustre: 215085:0:(client.c:2503:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1787894555/real 1787894555] req@0000000066b333fb x1874742978037632/t0(0) o400->MGC192.168.204.111@tcp@192.168.204.111@tcp:26/25 lens 224/224 e 0 to 1 dl 1787894571 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [10929.167902] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [10939.365408] Lustre: Evicted from MGS (at 192.168.204.111@tcp) after server handle changed from 0x13f519960d947742 to 0x13f519960d9479f0 [10939.379559] Lustre: MGC192.168.204.111@tcp: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10945.416696] Lustre: lustre-MDT0000-mdc-ffff8b4a904ed800: Connection restored to 192.168.204.111@tcp (at 192.168.204.111@tcp) [10956.448534] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10958.307051] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [10963.355291] Lustre: Unmounted lustre-client [11035.189832] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11041.399954] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11068.181859] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11069.510584] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [11072.596817] Lustre: Unmounted lustre-client [11102.379628] Key type lgssc unregistered [11102.638980] LNet: 225667:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11102.651942] LNetError: 225667:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11102.676569] LNet: Removed LNI 192.168.204.11@tcp [11103.409193] Key type .llcrypt unregistered [11103.413774] Key type ._llcrypt unregistered [11120.103659] Key type ._llcrypt registered [11120.104975] Key type .llcrypt registered [11121.481911] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 01:26:02 (1787894762) [11126.108286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11126.252800] alg: No test for adler32 (adler32-zlib) [11127.334298] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [11127.556922] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [11129.248204] Key type lgssc registered [11130.339401] Lustre: Echo OBD driver; http://www.lustre.org/ [11178.863112] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11184.195795] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11204.497068] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11206.076772] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [11211.904078] Lustre: Unmounted lustre-client [11276.832890] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11282.997932] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11306.360538] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11307.634980] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [11316.896196] Lustre: Unmounted lustre-client [11395.423452] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11402.396754] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11426.606666] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11437.663704] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [11440.609931] Lustre: Unmounted lustre-client [11478.739631] Key type lgssc unregistered [11479.064278] LNet: 232709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11479.080032] LNetError: 232709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11479.107633] LNet: Removed LNI 192.168.204.11@tcp [11480.020696] Key type .llcrypt unregistered [11480.028674] Key type ._llcrypt unregistered [11498.124430] Key type ._llcrypt registered [11498.127534] Key type .llcrypt registered [11499.806662] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 01:32:20 (1787895140) [11504.117157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11504.139843] alg: No test for adler32 (adler32-zlib) [11505.300829] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [11505.572480] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [11507.256201] Key type lgssc registered [11508.573604] Lustre: Echo OBD driver; http://www.lustre.org/ [11563.438442] Key type lgssc unregistered [11563.686962] LNet: 235028:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11563.691393] LNetError: 235028:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11563.708666] LNet: Removed LNI 192.168.204.11@tcp [11564.548794] Key type .llcrypt unregistered [11564.554324] Key type ._llcrypt unregistered [11599.660793] Key type ._llcrypt registered [11599.669077] Key type .llcrypt registered [11600.378287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11600.409919] alg: No test for adler32 (adler32-zlib) [11601.542422] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [11601.818586] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [11603.616795] Key type lgssc registered [11605.499633] Lustre: Echo OBD driver; http://www.lustre.org/ [11713.741458] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11721.955967] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11757.558465] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11807.350905] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [11809.598167] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 01:37:30 (1787895450) [11850.560774] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11857.123667] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11882.140804] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11909.683507] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11912.577289] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [11917.813942] Lustre: lustre-OST0001-osc-ffff8b4aa03fa000: Connection to lustre-OST0001 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [11924.492326] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11934.877603] Lustre: Unmounted lustre-client [11973.712502] Key type lgssc unregistered [11974.123253] LNet: 242154:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11974.136130] LNetError: 242154:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11974.157406] LNet: Removed LNI 192.168.204.11@tcp [11974.859179] Key type .llcrypt unregistered [11974.864198] Key type ._llcrypt unregistered [12010.461585] Key type ._llcrypt registered [12010.464825] Key type .llcrypt registered [12011.844491] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 01:40:52 (1787895652) [12027.021806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12027.045392] alg: No test for adler32 (adler32-zlib) [12028.179970] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [12028.460116] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [12030.176270] Key type lgssc registered [12031.569424] Lustre: Echo OBD driver; http://www.lustre.org/ [12088.734149] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12095.370640] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12128.763812] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 01:42:49 (1787895769) [12169.183979] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12175.353835] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12202.452516] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12205.457757] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [12217.908850] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [12227.100578] Lustre: Unmounted lustre-client [12352.237903] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12359.216234] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12414.515725] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12415.226017] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [12417.882180] Lustre: Unmounted lustre-client [12442.779873] Key type lgssc unregistered [12443.071765] LNet: 249857:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12443.081369] LNetError: 249857:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12444.137976] LNet: Removed LNI 192.168.204.11@tcp [12444.923563] Key type .llcrypt unregistered [12444.927616] Key type ._llcrypt unregistered [12476.979456] Key type ._llcrypt registered [12476.986469] Key type .llcrypt registered [12477.466580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12477.491576] alg: No test for adler32 (adler32-zlib) [12478.652440] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [12478.919772] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [12480.664628] Key type lgssc registered [12482.160900] Lustre: Echo OBD driver; http://www.lustre.org/ [12584.761800] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12591.711061] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12620.067552] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12667.447809] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [12669.508882] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 01:51:50 (1787896310) [12708.560279] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12715.396784] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12738.594637] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12744.354408] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [12748.521399] Lustre: Unmounted lustre-client [12780.637556] Key type lgssc unregistered [12780.974586] LNet: 256508:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12780.989783] LNetError: 256508:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12781.012320] LNet: Removed LNI 192.168.204.11@tcp [12782.141096] Key type .llcrypt unregistered [12782.144714] Key type ._llcrypt unregistered [12801.881609] Key type ._llcrypt registered [12801.890084] Key type .llcrypt registered [12803.505295] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [12805.434728] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 01:54:06 (1787896446) [12815.600498] Key type .llcrypt unregistered [12815.603841] Key type ._llcrypt unregistered [12826.911150] Key type ._llcrypt registered [12826.934271] Key type .llcrypt registered [12827.518614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12827.556957] alg: No test for adler32 (adler32-zlib) [12828.774530] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [12829.279129] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [12831.104302] Key type lgssc registered [12832.397523] Lustre: Echo OBD driver; http://www.lustre.org/ [12886.108319] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12902.477948] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [12905.381667] Lustre: Unmounted lustre-client [12934.001239] Key type lgssc unregistered [12934.343762] LNet: 259975:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12934.356849] LNetError: 259975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12934.382421] LNet: Removed LNI 192.168.204.11@tcp [12935.280836] Key type .llcrypt unregistered [12935.287394] Key type ._llcrypt unregistered [12952.121849] Key type ._llcrypt registered [12952.125222] Key type .llcrypt registered [12953.524666] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 01:56:34 (1787896594) [12956.190577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12956.243183] alg: No test for adler32 (adler32-zlib) [12957.353938] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [12957.615255] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [12959.280310] Key type lgssc registered [12960.716199] Lustre: Echo OBD driver; http://www.lustre.org/ [13049.578187] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13091.093862] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13097.078293] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13099.842183] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [13103.050218] Lustre: Unmounted lustre-client [13131.936870] Key type lgssc unregistered [13132.281262] LNet: 263516:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13132.297344] LNetError: 263516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13132.324512] LNet: Removed LNI 192.168.204.11@tcp [13133.194760] Key type .llcrypt unregistered [13133.202957] Key type ._llcrypt unregistered [13150.906623] Key type ._llcrypt registered [13150.908813] Key type .llcrypt registered [13152.404718] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 01:59:52 (1787896792) [13155.327576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13155.399504] alg: No test for adler32 (adler32-zlib) [13156.573488] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [13156.912327] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [13158.707253] Key type lgssc registered [13160.019814] Lustre: Echo OBD driver; http://www.lustre.org/ [13216.372774] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13223.775101] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13246.968472] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13259.354110] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [13264.379349] Lustre: lustre-MDT0000-mdc-ffff8b4a911a8800: Connection to lustre-MDT0000 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [13271.243925] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13271.297496] LustreError: 266689:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13271.896476] LustreError: 266700:0:(file.c:6167:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [13271.908956] LustreError: 266700:0:(file.c:6167:ll_inode_revalidate_fini()) Skipped 3 previous similar messages [13272.057832] LustreError: MGC192.168.204.111@tcp: Connection to MGS (at 192.168.204.111@tcp) was lost; in progress operations using this service will fail [13272.100269] Lustre: Unmounted lustre-client [13295.398324] Key type lgssc unregistered [13295.622091] LNet: 267102:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13295.629229] LNetError: 267102:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13295.646545] LNet: Removed LNI 192.168.204.11@tcp [13296.341091] Key type .llcrypt unregistered [13296.345543] Key type ._llcrypt unregistered [13313.162435] Key type ._llcrypt registered [13313.165512] Key type .llcrypt registered [13314.746068] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 02:02:35 (1787896955) [13317.524752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13317.586015] alg: No test for adler32 (adler32-zlib) [13318.739982] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [13319.034160] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [13320.760157] Key type lgssc registered [13322.230130] Lustre: Echo OBD driver; http://www.lustre.org/ [13379.901928] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13386.999880] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13409.115257] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13410.863537] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [13415.916161] Lustre: lustre-MDT0001-mdc-ffff8b4a911ab000: Connection to lustre-MDT0001 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [13421.601692] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [13423.987534] Lustre: Unmounted lustre-client [13447.910173] Key type lgssc unregistered [13448.110329] LNet: 270681:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13448.117386] LNetError: 270681:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13448.145114] LNet: Removed LNI 192.168.204.11@tcp [13449.368557] Key type .llcrypt unregistered [13449.373803] Key type ._llcrypt unregistered [13464.774108] Key type ._llcrypt registered [13464.780710] Key type .llcrypt registered [13466.365535] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 02:05:06 (1787897106) [13485.192837] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13485.230156] alg: No test for adler32 (adler32-zlib) [13486.354189] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [13486.596944] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [13488.336475] Key type lgssc registered [13489.546393] Lustre: Echo OBD driver; http://www.lustre.org/ [13608.783951] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13610.508051] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [13655.543671] Lustre: lustre-MDT0001-mdc-ffff8b4a87952800: Connection to lustre-MDT0001 (at 192.168.204.111@tcp) was lost; in progress operations using this service will wait for recovery to complete [13663.825929] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [13674.183588] Lustre: Unmounted lustre-client [13720.451078] Key type lgssc unregistered [13720.751070] LNet: 276469:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13720.762438] LNetError: 276469:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13720.785253] LNet: Removed LNI 192.168.204.11@tcp [13721.580990] Key type .llcrypt unregistered [13721.583396] Key type ._llcrypt unregistered [13740.400234] Key type ._llcrypt registered [13740.402211] Key type .llcrypt registered [13741.822259] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 02:09:42 (1787897382) [13744.120617] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [13746.118604] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 02:09:46 (1787897386) [13748.073501] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [13749.755168] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 02:09:50 (1787897390) [13751.744538] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [13754.320424] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 02:09:54 (1787897394) [13756.686169] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [13758.951619] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 02:09:59 (1787897399) [13760.635647] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [13762.646365] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 02:10:03 (1787897403) [13772.473539] Key type .llcrypt unregistered [13772.479853] Key type ._llcrypt unregistered [13778.913605] Key type ._llcrypt registered [13778.921785] Key type .llcrypt registered [13779.486647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13779.516595] alg: No test for adler32 (adler32-zlib) [13780.583285] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [13781.002750] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [13782.728351] Key type lgssc registered [13784.830209] Lustre: Echo OBD driver; http://www.lustre.org/ [13873.212829] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13880.057482] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13907.468491] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13909.189779] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [13912.682365] Lustre: Unmounted lustre-client [13954.959141] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 02:13:15 (1787897595) [13995.856499] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14002.358831] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14040.575794] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14041.101655] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [14044.623607] Lustre: Unmounted lustre-client [14089.884690] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 02:15:30 (1787897730) [14095.394716] LNet: 1 local NIs in recovery (showing 1): 192.168.204.11@tcp [14751.977267] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14758.712453] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14797.055124] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14909.556988] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 02:29:10 (1787898550) [14923.448946] Key type lgssc unregistered [14923.774991] LNet: 289997:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14923.782637] LNetError: 289997:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14924.843591] LNet: Removed LNI 192.168.204.11@tcp [14925.673658] Key type .llcrypt unregistered [14925.675841] Key type ._llcrypt unregistered [15045.859081] Key type ._llcrypt registered [15045.863474] Key type .llcrypt registered [15046.723936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15046.759906] alg: No test for adler32 (adler32-zlib) [15048.127599] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [15048.552315] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [15050.304837] Key type lgssc registered [15051.909440] Lustre: Echo OBD driver; http://www.lustre.org/ [15156.019492] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15163.808702] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15191.109445] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15235.785417] Key type lgssc unregistered [15236.141900] LNet: 293693:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15236.151516] LNetError: 293693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15236.179097] LNet: Removed LNI 192.168.204.11@tcp [15237.247720] Key type .llcrypt unregistered [15237.252435] Key type ._llcrypt unregistered [15268.130954] Key type ._llcrypt registered [15268.136059] Key type .llcrypt registered [15269.436945] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 02:35:10 (1787898910) [15295.161424] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [15295.211990] alg: No test for adler32 (adler32-zlib) [15296.360896] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [15296.745645] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [15298.584852] Key type lgssc registered [15300.677116] Lustre: Echo OBD driver; http://www.lustre.org/ [15357.398394] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 02:36:37 (1787898997) [15370.737536] Key type lgssc unregistered [15370.991133] LNet: 297247:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15371.003062] LNetError: 297247:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15371.028723] LNet: Removed LNI 192.168.204.11@tcp [15371.761825] Key type .llcrypt unregistered [15371.764659] Key type ._llcrypt unregistered [15384.645200] Key type ._llcrypt registered [15384.664558] Key type .llcrypt registered [15384.989627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15385.007262] alg: No test for adler32 (adler32-zlib) [15386.105663] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [15386.326099] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [15388.033476] Key type lgssc registered [15389.420060] Lustre: Echo OBD driver; http://www.lustre.org/ [15458.121441] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15465.468536] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15495.892913] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [15842.023532] LNet: Added LNI 192.168.204.11@tcp50 [8/256/0/180] [15844.893769] LNet: Removed LNI 192.168.204.11@tcp [15847.386793] Lustre: 301654:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8b4a82bca000: config has no valid NIDs, force dynamic NIDs [15848.679988] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [15848.935745] Lustre: Unmounted lustre-client [15852.144373] Key type lgssc unregistered [15852.511090] LNet: 301912:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [15852.524992] LNetError: 301912:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [15852.548987] LNet: Removed LNI 192.168.204.11@tcp50 [15853.204754] Key type .llcrypt unregistered [15853.210755] Key type ._llcrypt unregistered [15873.799404] Key type ._llcrypt registered [15873.820550] Key type .llcrypt registered [15874.188260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [15874.210573] alg: No test for adler32 (adler32-zlib) [15875.384819] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [15875.762923] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [15877.496420] Key type lgssc registered [15879.375985] Lustre: Echo OBD driver; http://www.lustre.org/ [15943.362559] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [15950.429626] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [15959.060867] LNet: Added LNI 192.168.204.11@tcp50 [8/256/0/180] [15960.117175] LNet: Removed LNI 192.168.204.11@tcp [15961.371969] Lustre: 304601:0:(ldlm_lib.c:561:client_obd_setup()) lustre-MDT0000-mdc-ffff8b4a87953800: config has no valid NIDs, force dynamic NIDs [15962.600155] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [15963.411654] Lustre: Unmounted lustre-client [16001.852768] Key type lgssc unregistered [16002.354519] LNet: 305471:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16002.371605] LNetError: 305471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16002.392240] LNet: Removed LNI 192.168.204.11@tcp50 [16003.192156] Key type .llcrypt unregistered [16003.196502] Key type ._llcrypt unregistered [16016.908391] Key type ._llcrypt registered [16016.910494] Key type .llcrypt registered [16018.256618] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 02:47:38 (1787899658) [16037.515304] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16037.598542] alg: No test for adler32 (adler32-zlib) [16038.670651] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [16038.993381] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [16040.736212] Key type lgssc registered [16042.125937] Lustre: Echo OBD driver; http://www.lustre.org/ [16137.762872] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16146.632265] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16176.511036] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16213.968881] Key type lgssc unregistered [16214.441669] LNet: 310218:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16214.451666] LNetError: 310218:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16214.468621] LNet: Removed LNI 192.168.204.11@tcp [16215.597764] Key type .llcrypt unregistered [16215.599764] Key type ._llcrypt unregistered [16229.718734] Key type ._llcrypt registered [16229.724421] Key type .llcrypt registered [16231.349359] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 02:51:11 (1787899871) [16234.081810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16234.106657] alg: No test for adler32 (adler32-zlib) [16235.246339] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [16235.484961] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [16237.169244] Key type lgssc registered [16238.394542] Lustre: Echo OBD driver; http://www.lustre.org/ [16294.336714] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16300.931106] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16325.304766] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16328.193952] Lustre: Mounted lustre-client - version 2.17.57_84_gd387c42 [16397.822203] Lustre: Mounted fs15246-client - version 2.17.57_84_gd387c42 [16415.334032] Lustre: Unmounted fs15246-client [16444.110207] Lustre: Unmounted lustre-client [16476.753962] Key type lgssc unregistered [16477.091465] LNet: 315074:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16477.100830] LNetError: 315074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16477.118779] LNet: Removed LNI 192.168.204.11@tcp [16477.965496] Key type .llcrypt unregistered [16477.967342] Key type ._llcrypt unregistered [16491.422221] Key type ._llcrypt registered [16491.427745] Key type .llcrypt registered [16493.127360] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 02:55:33 (1787900133) [16495.624934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [16495.675207] alg: No test for adler32 (adler32-zlib) [16496.789275] Lustre: Lustre: Build Version: 2.17.57_84_gd387c42 [16497.130786] LNet: Added LNI 192.168.204.11@tcp [8/256/0/180] [16498.970081] Key type lgssc registered [16500.571520] Lustre: Echo OBD driver; http://www.lustre.org/ [16648.576898] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [16654.555178] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [16679.287290] Lustre: DEBUG MARKER: oleg411-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [16800.269720] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 16485 sec ========= 03:00:40 (1787900440) [16802.361794] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 03:00:42 (1787900442) === [16807.032594] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 03:00:47 (1787900447) === [16845.290980] Key type lgssc unregistered [16845.569042] LNet: 324363:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [16845.590638] LNetError: 324363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [16845.608270] LNet: Removed LNI 192.168.204.11@tcp [16846.430343] Key type .llcrypt unregistered [16846.436241] Key type ._llcrypt unregistered