[ 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 484249689 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002383] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009011] pid_max: default: 32768 minimum: 301 [ 0.011124] LSM: Security Framework initializing [ 0.012050] Yama: becoming mindful. [ 0.013035] SELinux: Initializing. [ 0.014099] *** VALIDATE selinux *** [ 0.024428] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029856] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030145] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033106] *** VALIDATE tmpfs *** [ 0.035371] *** VALIDATE proc *** [ 0.036267] *** VALIDATE cgroup *** [ 0.037010] *** VALIDATE cgroup2 *** [ 0.039156] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.040142] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.041011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042031] Spectre V2 : User space: Vulnerable [ 0.043000] Speculative Store Bypass: Vulnerable [ 0.043000] debug: unmapping init [mem 0xffffffff9b659000-0xffffffff9b660fff] [ 0.043000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043698] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044025] ... version: 2 [ 0.045014] ... bit width: 48 [ 0.046014] ... generic registers: 4 [ 0.047013] ... value mask: 0000ffffffffffff [ 0.048016] ... max period: 00007fffffffffff [ 0.049018] ... fixed-purpose events: 3 [ 0.050011] ... event mask: 000000070000000f [ 0.051302] rcu: Hierarchical SRCU implementation. [ 0.053416] smp: Bringing up secondary CPUs ... [ 0.054575] x86: Booting SMP configuration: [ 0.055029] .... node #0, CPUs: #1 #2 #3 [ 0.062085] smp: Brought up 1 node, 4 CPUs [ 0.064012] smpboot: Max logical packages: 1 [ 0.065013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.106607] node 0 deferred pages initialised in 39ms [ 0.113392] devtmpfs: initialized [ 0.114264] x86/mm: Memory block size: 128MB [ 0.118325] gcov: version magic: 0x41383552 [ 0.123385] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.124080] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.125242] pinctrl core: initialized pinctrl subsystem [ 0.126235] [ 0.126808] ************************************************************* [ 0.127013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.128012] ** ** [ 0.129013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.130016] ** ** [ 0.131013] ** This means that this kernel is built to expose internal ** [ 0.132013] ** IOMMU data structures, which may compromise security on ** [ 0.133012] ** your system. ** [ 0.134014] ** ** [ 0.135013] ** If you see this message and you are not debugging the ** [ 0.136012] ** kernel, report this immediately to your vendor! ** [ 0.137024] ** ** [ 0.138010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.139013] ************************************************************* [ 0.140840] NET: Registered protocol family 16 [ 0.141525] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.142058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.143122] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.144619] cpuidle: using governor menu [ 0.147030] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.150615] PCI: Using configuration type 1 for base access [ 0.153149] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.162541] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.163019] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.166014] cryptd: max_cpu_qlen set to 1000 [ 0.168207] ACPI: Added _OSI(Module Device) [ 0.169011] ACPI: Added _OSI(Processor Device) [ 0.170013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.171013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.175186] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.177522] ACPI: Interpreter enabled [ 0.178070] ACPI: PM: (supports S0 S3 S4 S5) [ 0.179011] ACPI: Using IOAPIC for interrupt routing [ 0.180090] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.181377] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.191866] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.192049] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.193030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.194076] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.196326] acpiphp: Slot [2] registered [ 0.197106] acpiphp: Slot [5] registered [ 0.198096] acpiphp: Slot [6] registered [ 0.199108] acpiphp: Slot [3] registered [ 0.200128] acpiphp: Slot [4] registered [ 0.201076] acpiphp: Slot [7] registered [ 0.201918] acpiphp: Slot [8] registered [ 0.202119] acpiphp: Slot [9] registered [ 0.203091] acpiphp: Slot [10] registered [ 0.204094] acpiphp: Slot [11] registered [ 0.205063] acpiphp: Slot [12] registered [ 0.206074] acpiphp: Slot [13] registered [ 0.207097] acpiphp: Slot [14] registered [ 0.208077] acpiphp: Slot [15] registered [ 0.208939] acpiphp: Slot [16] registered [ 0.209099] acpiphp: Slot [17] registered [ 0.210107] acpiphp: Slot [18] registered [ 0.211088] acpiphp: Slot [19] registered [ 0.212070] acpiphp: Slot [20] registered [ 0.213080] acpiphp: Slot [21] registered [ 0.214083] acpiphp: Slot [22] registered [ 0.215094] acpiphp: Slot [23] registered [ 0.216096] acpiphp: Slot [24] registered [ 0.217103] acpiphp: Slot [25] registered [ 0.218118] acpiphp: Slot [26] registered [ 0.219098] acpiphp: Slot [27] registered [ 0.220091] acpiphp: Slot [28] registered [ 0.221110] acpiphp: Slot [29] registered [ 0.222141] acpiphp: Slot [30] registered [ 0.223110] acpiphp: Slot [31] registered [ 0.224060] PCI host bridge to bus 0000:00 [ 0.225019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.226022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.227060] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.228024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.229024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.230033] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.235122] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.240729] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.243306] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.253015] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.259032] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.262019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.266019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.270024] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.273067] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.277336] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.281043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.286078] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.290014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.301020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.305014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.314109] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.315000] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.315000] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.340014] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.350479] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.357014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.363013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.384013] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.394000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.397387] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.399353] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.403689] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.406235] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.412003] iommu: Default domain type: Passthrough [ 0.416201] SCSI subsystem initialized [ 0.417163] ACPI: bus type USB registered [ 0.418105] usbcore: registered new interface driver usbfs [ 0.421080] usbcore: registered new interface driver hub [ 0.423088] usbcore: registered new device driver usb [ 0.425172] pps_core: LinuxPPS API ver. 1 registered [ 0.427011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.431075] PTP clock support registered [ 0.434064] EDAC MC: Ver: 3.0.0 [ 0.436224] PCI: Using ACPI for IRQ routing [ 0.446503] NetLabel: Initializing [ 0.451011] NetLabel: domain hash size = 128 [ 0.457964] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.470077] NetLabel: unlabeled traffic allowed by default [ 0.478235] vgaarb: loaded [ 0.484047] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.487012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.546116] clocksource: Switched to clocksource kvm-clock [ 0.751579] VFS: Disk quotas dquot_6.6.0 [ 0.753134] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.755457] *** VALIDATE ramfs *** [ 0.756647] *** VALIDATE hugetlbfs *** [ 0.758092] pnp: PnP ACPI init [ 0.760383] pnp: PnP ACPI: found 6 devices [ 0.776844] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.780277] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.782166] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.783989] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.786143] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.788629] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.791090] NET: Registered protocol family 2 [ 0.793711] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.799056] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.803287] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.808350] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.840127] TCP: Hash tables configured (established 65536 bind 65536) [ 0.848458] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.853459] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.856254] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.859093] NET: Registered protocol family 1 [ 0.863059] RPC: Registered named UNIX socket transport module. [ 0.865387] RPC: Registered udp transport module. [ 0.866708] RPC: Registered tcp transport module. [ 0.868189] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.870194] NET: Registered protocol family 44 [ 0.871688] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.873740] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.875827] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.878508] PCI: CLS 0 bytes, default 64 [ 0.880233] Unpacking initramfs... [ 3.252858] debug: unmapping init [mem 0xffff9a4f7cc64000-0xffff9a4f7ffcffff] [ 3.259417] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.262127] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 3.265633] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.968281] Initialise system trusted keyrings [ 3.970333] Key type blacklist registered [ 3.972427] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.982877] zbud: loaded [ 3.986714] *** VALIDATE nfs *** [ 3.988536] *** VALIDATE nfs4 *** [ 3.990665] pstore: using deflate compression [ 3.995490] Platform Keyring initialized [ 4.132671] NET: Registered protocol family 38 [ 4.135156] Key type asymmetric registered [ 4.137366] Asymmetric key parser 'x509' registered [ 4.139740] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.150640] io scheduler mq-deadline registered [ 4.152411] io scheduler kyber registered [ 4.154795] io scheduler bfq registered [ 4.156939] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.160681] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.163747] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.167603] ACPI: Power Button [PWRF] [ 4.174427] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.190788] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.200935] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.246597] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.282171] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.287276] Non-volatile memory driver v1.3 [ 4.289339] Linux agpgart interface v0.103 [ 4.325162] virtio_blk virtio1: [vda] 145904 512-byte logical blocks (74.7 MB/71.2 MiB) [ 4.329099] vda: detected capacity change from 0 to 74702848 [ 4.392257] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.395765] vdb: detected capacity change from 0 to 1073741824 [ 4.413086] libphy: Fixed MDIO Bus: probed [ 4.424929] usbcore: registered new interface driver usbserial_generic [ 4.431472] usbserial: USB Serial support registered for generic [ 4.437345] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.445187] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.449485] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.460484] mousedev: PS/2 mouse device common for all mice [ 4.471088] rtc_cmos 00:05: RTC can wake from S4 [ 4.484832] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.490255] rtc_cmos 00:05: registered as rtc0 [ 4.503907] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.508802] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.518854] intel_pstate: CPU model not supported [ 4.526916] hid: raw HID events driver (C) Jiri Kosina [ 4.530244] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.535234] usbcore: registered new interface driver usbhid [ 4.578100] usbhid: USB HID core driver [ 4.591074] drop_monitor: Initializing network drop monitor service [ 4.595302] Initializing XFRM netlink socket [ 4.605643] NET: Registered protocol family 10 [ 4.612027] Segment Routing with IPv6 [ 4.614606] NET: Registered protocol family 17 [ 4.619671] mpls_gso: MPLS GSO support [ 4.633525] RAS: Correctable Errors collector initialized. [ 4.637202] AVX version of gcm_enc/dec engaged. [ 4.639698] AES CTR mode by8 optimization enabled [ 4.983668] sched_clock: Marking stable (4983647756, 0)->(6202710703, -1219062947) [ 4.988479] registered taskstats version 1 [ 4.990781] Loading compiled-in X.509 certificates [ 4.994318] zswap: loaded using pool lzo/zbud [ 5.033583] Key type big_key registered [ 5.049990] Key type encrypted registered [ 5.052284] ima: No TPM chip found, activating TPM-bypass! [ 5.057440] ima: Allocated hash algorithm: sha1 [ 5.059774] ima: No architecture policies found [ 5.063127] evm: Initialising EVM extended attributes: [ 5.066968] evm: security.selinux [ 5.068400] evm: security.ima [ 5.070544] evm: security.capability [ 5.074596] evm: HMAC attrs: 0x1 [ 5.080191] rtc_cmos 00:05: setting system clock to 2026-08-09 20:54:47 UTC (1786308887) [ 5.090796] debug: unmapping init [mem 0xffffffff9c603000-0xffffffff9c7fffff] [ 5.098416] debug: unmapping init [mem 0xffffffff9b382000-0xffffffff9b658fff] [ 5.121652] Write protecting the kernel read-only data: 28672k [ 5.125448] debug: unmapping init [mem 0xffffffff99a03000-0xffffffff99bfffff] [ 5.128480] debug: unmapping init [mem 0xffffffff9a314000-0xffffffff9a3fffff] [ 5.162971] 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) [ 5.172961] systemd[1]: Detected virtualization kvm. [ 5.175417] systemd[1]: Detected architecture x86-64. [ 5.177728] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.210482] systemd[1]: No hostname configured. [ 5.212692] systemd[1]: Set hostname to . [ 5.216437] random: systemd: uninitialized urandom read (16 bytes read) [ 5.220774] systemd[1]: Initializing machine ID from random generator. [ 5.354446] random: ln: uninitialized urandom read (6 bytes read) [ 5.556143] random: systemd: uninitialized urandom read (16 bytes read) [ 5.559361] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 5.565530] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 5.578204] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.552567] device-mapper: uevent: version 1.0.3 [ 7.555208] 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... [ 9.281522] virtio_net virtio0 ens2: renamed from eth0 [ 9.361122] random: fast init done [ 9.589196] scsi host0: ata_piix [ 9.811393] scsi host1: ata_piix [ 9.814676] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.820376] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.177510] random: crng init done [ 15.179493] random: 7 urandom warning(s) missed due to ratelimiting [ 16.369663] dracut-initqueue[588]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 18.160356] 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ 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 System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.655953] printk: systemd: 26 output lines suppressed due to ratelimiting [ 21.232595] SELinux: Disabled at runtime. [ 21.354932] 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) [ 21.366632] systemd[1]: Detected virtualization kvm. [ 21.369176] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.314370] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.317927] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.323660] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.329230] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.336695] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.348275] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.373529] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target RPC Port Mapper. [ 22.568791] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 24.054450] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 24.965856] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.162875] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.627940] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 25.862416] EDAC sbridge: Ver: 1.1.2 [ 29.330147] Key type dns_resolver registered [ 29.709984] NFS: Registering the id_resolver key type [ 29.712268] Key type id_resolver registered [ 29.714108] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. Starting Hostname Service... [ 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 Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ 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. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg312-client login: [ 87.451895] libcfs: loading out-of-tree module taints kernel. [ 87.512495] Key type ._llcrypt registered [ 87.530623] Key type .llcrypt registered [ 88.135291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 88.146594] alg: No test for adler32 (adler32-zlib) [ 89.567972] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 90.094702] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 91.792158] Key type lgssc registered [ 93.246550] hrtimer: interrupt took 5566729 ns [ 93.690926] Lustre: Echo OBD driver; http://www.lustre.org/ [ 244.716940] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 249.277622] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 261.049502] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing check_logdir /tmp/testlogs/ [ 265.937726] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing yml_node [ 269.719214] Lustre: DEBUG MARKER: Client: 2.17.56.55 [ 270.309495] Lustre: lustre-OST0000-osc-ffff9a4fc9264000: disconnect after 23s idle [ 273.230906] Lustre: DEBUG MARKER: MDS: 2.17.56.55 [ 275.721294] Lustre: DEBUG MARKER: OSS: 2.17.56.55 [ 277.709562] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Sun Aug 9 16:59:18 EDT 2026 [ 294.487210] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball [ 296.131705] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 298.643305] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 308.940348] Lustre: Unmounted lustre-client [ 412.331705] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 418.496157] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 441.439895] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 476.254642] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 17:02:37 (1786309357) [ 478.433947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_33a mixed loopback and real device not working [ 480.542396] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 17:02:41 (1786309361) [ 512.559794] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 518.609643] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 540.898731] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 541.399520] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 548.558779] Lustre: Unmounted lustre-client [ 582.722980] Key type lgssc unregistered [ 582.935487] LNet: 15036:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 582.944900] LNetError: 15036:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 582.971506] LNet: Removed LNI 192.168.203.12@tcp [ 583.535240] Key type .llcrypt unregistered [ 583.538575] Key type ._llcrypt unregistered [ 598.909789] Key type ._llcrypt registered [ 598.920857] Key type .llcrypt registered [ 600.262756] Lustre: DEBUG MARKER: == conf-sanity test 33c: Mount ost with a large index number ========================================================== 17:04:41 (1786309481) [ 601.954104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 601.981389] alg: No test for adler32 (adler32-zlib) [ 603.009664] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 603.343913] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 605.000277] Key type lgssc registered [ 606.156977] Lustre: Echo OBD driver; http://www.lustre.org/ [ 673.321828] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 755.434862] Lustre: Unmounted lustre-client [ 783.022647] Key type lgssc unregistered [ 783.242874] LNet: 18829:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 783.248714] LNetError: 18829:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 783.269542] LNet: Removed LNI 192.168.203.12@tcp [ 783.816447] Key type .llcrypt unregistered [ 783.819436] Key type ._llcrypt unregistered [ 794.345740] Key type ._llcrypt registered [ 794.349516] Key type .llcrypt registered [ 795.668238] Lustre: DEBUG MARKER: == conf-sanity test 33d: Don't panic when enable project quota ========================================================== 17:07:56 (1786309676) [ 798.371411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 798.574733] alg: No test for adler32 (adler32-zlib) [ 799.584191] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 799.740892] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 801.512201] Key type lgssc registered [ 802.672573] Lustre: Echo OBD driver; http://www.lustre.org/ [ 849.937305] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 854.935210] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 874.957409] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 877.609211] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 886.575592] Lustre: Unmounted lustre-client [ 912.796678] Key type lgssc unregistered [ 913.089356] LNet: 22431:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 913.103170] LNetError: 22431:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 913.131296] LNet: Removed LNI 192.168.203.12@tcp [ 914.148518] Key type .llcrypt unregistered [ 914.150995] Key type ._llcrypt unregistered [ 923.973107] Key type ._llcrypt registered [ 923.982597] Key type .llcrypt registered [ 924.982363] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 17:10:06 (1786309806) [ 927.184984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 927.193440] alg: No test for adler32 (adler32-zlib) [ 928.153357] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 928.357464] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 930.056167] Key type lgssc registered [ 930.866833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 977.872114] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 982.582971] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1001.428693] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1004.427309] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1009.202443] Lustre: Unmounted lustre-client [ 1034.410568] Key type lgssc unregistered [ 1034.591052] LNet: 25625:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1034.595754] LNetError: 25625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1034.618077] LNet: Removed LNI 192.168.203.12@tcp [ 1035.386758] Key type .llcrypt unregistered [ 1035.394539] Key type ._llcrypt unregistered [ 1050.281674] Key type ._llcrypt registered [ 1050.286452] Key type .llcrypt registered [ 1051.898555] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 17:12:12 (1786309932) [ 1054.557991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1054.687510] alg: No test for adler32 (adler32-zlib) [ 1055.810571] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 1056.125959] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 1057.865306] Key type lgssc registered [ 1058.872782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1105.748723] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1110.440650] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1129.376518] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1132.716055] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1138.154579] Lustre: lustre-MDT0000-mdc-ffff9a4fc9f2f000: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1143.272371] Lustre: lustre-MDT0001-mdc-ffff9a4fc9f2f000: Connection to lustre-MDT0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1148.785770] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1158.624184] Lustre: 26584:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786310025/real 1786310025] req@000000000b1d8db3 x1873081729553920/t0(0) o400->MGC192.168.203.112@tcp@192.168.203.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1786310041 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 1158.648587] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 1159.116785] Lustre: Unmounted lustre-client [ 1175.374761] Key type lgssc unregistered [ 1175.598713] LNet: 29281:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1175.602949] LNetError: 29281:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1175.612878] LNet: Removed LNI 192.168.203.12@tcp [ 1176.218872] Key type .llcrypt unregistered [ 1176.221132] Key type ._llcrypt unregistered [ 1188.734459] Key type ._llcrypt registered [ 1188.736873] Key type .llcrypt registered [ 1189.757608] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 17:14:31 (1786310071) [ 1192.161620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1192.218236] alg: No test for adler32 (adler32-zlib) [ 1193.283933] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 1193.511331] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 1195.195443] Key type lgssc registered [ 1196.189622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1241.283408] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1246.349624] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1263.552165] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1265.041900] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1270.267507] Lustre: lustre-OST0000-osc-ffff9a4fc7def800: Connection to lustre-OST0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1276.252292] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1286.674834] Lustre: Unmounted lustre-client [ 1312.039716] Key type lgssc unregistered [ 1312.331640] LNet: 32895:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1312.339111] LNetError: 32895:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1312.350295] LNet: Removed LNI 192.168.203.12@tcp [ 1313.274331] Key type .llcrypt unregistered [ 1313.276302] Key type ._llcrypt unregistered [ 1328.121343] Key type ._llcrypt registered [ 1328.123663] Key type .llcrypt registered [ 1329.319882] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 17:16:50 (1786310210) [ 1331.919938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1331.954900] alg: No test for adler32 (adler32-zlib) [ 1333.043328] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 1333.438228] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 1335.088129] Key type lgssc registered [ 1336.416678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1381.869389] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1387.262230] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1407.576639] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1410.704907] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1413.920848] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1418.528428] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 1430.437866] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2026-08-0917h18m31s [ 1432.528761] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 1436.135862] Lustre: lustre-MDT0000-mdc-ffff9a4fc9263800: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1442.980130] LustreError: 36686:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff9a4fc9263800: can't stat MDS #0: rc = -19 [ 1444.471235] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 1453.563120] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 1453.593845] Lustre: Evicted from MGS (at 192.168.203.112@tcp) after server handle changed from 0x6b3853907fbcd793 to 0x6b3853907fbcda56 [ 1453.607520] Lustre: MGC192.168.203.112@tcp: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 1454.649407] LustreError: lustre-MDT0000-mdc-ffff9a4fc9263800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1454.659931] Lustre: lustre-MDT0000-mdc-ffff9a4fc9263800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 1459.670718] Lustre: DEBUG MARKER: Wait for df (36686) ... [ 1461.754755] Lustre: DEBUG MARKER: done [ 1463.766893] Lustre: Unmounted lustre-client [ 1500.096506] Key type lgssc unregistered [ 1500.424690] LNet: 37706:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1500.443885] LNetError: 37706:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1500.481146] LNet: Removed LNI 192.168.203.12@tcp [ 1501.147149] Key type .llcrypt unregistered [ 1501.149527] Key type ._llcrypt unregistered [ 1532.519098] Key type ._llcrypt registered [ 1532.521119] Key type .llcrypt registered [ 1533.993287] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 17:20:14 (1786310414) [ 1536.583506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1536.616689] alg: No test for adler32 (adler32-zlib) [ 1537.746854] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 1537.987900] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 1539.633580] Key type lgssc registered [ 1540.652195] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1593.473867] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1599.397842] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1617.972678] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1621.061361] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1624.889902] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2026-08-0917h21m45s [ 1627.187136] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 1633.963034] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 1635.879363] Lustre: DEBUG MARKER: Creating a test file and stat it [ 1656.801158] Lustre: 41862:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786310518/real 1786310518] req@000000008b3294a8 x1873082234967808/t0(0) o101->lustre-MDT0000-mdc-ffff9a4fc4d67800@192.168.203.112@tcp:12/10 lens 576/1152 e 0 to 1 dl 1786310538 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:1 [ 1656.842475] Lustre: lustre-MDT0000-mdc-ffff9a4fc4d67800: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1656.904648] Lustre: lustre-MDT0000-mdc-ffff9a4fc4d67800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 1677.669842] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 1680.332199] Lustre: DEBUG MARKER: done [ 1683.576856] Lustre: Unmounted lustre-client [ 1709.936852] Key type lgssc unregistered [ 1710.168773] LNet: 42742:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1710.184534] LNetError: 42742:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1710.308272] LNet: Removed LNI 192.168.203.12@tcp [ 1710.797411] Key type .llcrypt unregistered [ 1710.801329] Key type ._llcrypt unregistered [ 1740.500116] Key type ._llcrypt registered [ 1740.507602] Key type .llcrypt registered [ 1741.729201] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 17:23:42 (1786310622) [ 1743.068653] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 1745.280576] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 17:23:46 (1786310626) [ 1748.875228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1748.889845] alg: No test for adler32 (adler32-zlib) [ 1749.954347] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 1750.152955] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 1751.816182] Key type lgssc registered [ 1752.961413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1778.696662] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 17:24:20 (1786310660) [ 1808.047465] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1813.355675] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1832.895495] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1835.635648] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1838.958968] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 1841.977563] Lustre: Unmounted lustre-client [ 1859.361711] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 1904.402699] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1910.086273] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1910.610385] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1913.977327] Lustre: Unmounted lustre-client [ 1973.583841] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1979.085570] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1979.623391] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 1983.217659] Lustre: Unmounted lustre-client [ 2000.522479] Lustre: DEBUG MARKER: files compared the same [ 2023.584333] Key type lgssc unregistered [ 2023.869789] LNet: 50377:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2023.876325] LNetError: 50377:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2023.906688] LNet: Removed LNI 192.168.203.12@tcp [ 2024.527239] Key type .llcrypt unregistered [ 2024.573578] Key type ._llcrypt unregistered [ 2041.131630] Key type ._llcrypt registered [ 2041.133401] Key type .llcrypt registered [ 2042.853925] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 17:28:43 (1786310923) [ 2045.892895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2045.911533] alg: No test for adler32 (adler32-zlib) [ 2046.943411] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 2047.286910] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 2048.936166] Key type lgssc registered [ 2050.100915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2095.659116] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2102.911355] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2122.691431] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2125.474624] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 2132.579196] Lustre: Unmounted lustre-client [ 2159.560598] Key type lgssc unregistered [ 2159.821977] LNet: 53964:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2159.830507] LNetError: 53964:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2159.845368] LNet: Removed LNI 192.168.203.12@tcp [ 2160.634625] Key type .llcrypt unregistered [ 2160.637634] Key type ._llcrypt unregistered [ 2177.623550] Key type ._llcrypt registered [ 2177.626709] Key type .llcrypt registered [ 2178.854192] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 17:30:59 (1786311059) [ 2181.447602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2181.463718] alg: No test for adler32 (adler32-zlib) [ 2182.532565] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 2182.824350] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 2184.512803] Key type lgssc registered [ 2185.525507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2267.096203] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2300.671450] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2306.064461] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2333.954618] Key type lgssc unregistered [ 2334.196054] LNet: 57459:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2334.200500] LNetError: 57459:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2334.212718] LNet: Removed LNI 192.168.203.12@tcp [ 2334.954798] Key type .llcrypt unregistered [ 2334.962713] Key type ._llcrypt unregistered [ 2349.220885] Key type ._llcrypt registered [ 2349.222989] Key type .llcrypt registered [ 2350.751622] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 17:33:51 (1786311231) [ 2354.393979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2354.413875] alg: No test for adler32 (adler32-zlib) [ 2355.523692] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 2355.770689] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 2357.504195] Key type lgssc registered [ 2358.521241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2425.511646] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 2432.112590] Lustre: Unmounted lustre-client [ 2463.881829] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 17:35:45 (1786311345) [ 2575.957094] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2590.952762] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 2597.989138] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2597.996246] LustreError: 63935:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9a4fc95bd000: inode [0x200000402:0x1:0x0] mdc close failed: rc = -108 [ 2598.022691] Lustre: 58444:0:(llite_lib.c:4340:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.112@tcp:/lustre/fid: [0x200000402:0x1:0x0]/ may get corrupted (rc -108) [ 2608.348419] Lustre: Unmounted lustre-client [ 2659.399409] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 17:39:00 (1786311540) [ 2673.036940] Key type lgssc unregistered [ 2673.237217] LNet: 65153:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2673.244402] LNetError: 65153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2673.258789] LNet: Removed LNI 192.168.203.12@tcp [ 2674.040148] Key type .llcrypt unregistered [ 2674.042250] Key type ._llcrypt unregistered [ 2687.448511] Key type ._llcrypt registered [ 2687.453245] Key type .llcrypt registered [ 2687.731568] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2687.756627] alg: No test for adler32 (adler32-zlib) [ 2689.042935] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 2689.482879] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 2691.176384] Key type lgssc registered [ 2692.159716] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2739.516608] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2745.784941] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2766.206857] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2769.510220] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 2773.674962] Lustre: Unmounted lustre-client [ 2804.182664] Key type lgssc unregistered [ 2804.468601] LNet: 68170:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2804.476593] LNetError: 68170:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2805.543989] LNet: Removed LNI 192.168.203.12@tcp [ 2806.392102] Key type .llcrypt unregistered [ 2806.398506] Key type ._llcrypt unregistered [ 2820.154515] Key type ._llcrypt registered [ 2820.157983] Key type .llcrypt registered [ 2820.635224] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2820.649656] alg: No test for adler32 (adler32-zlib) [ 2821.755313] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 2822.190464] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 2824.034350] Key type lgssc registered [ 2825.006956] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2935.756272] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2941.362948] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2962.997976] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2964.718544] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 2966.152423] Lustre: Unmounted lustre-client [ 2992.669571] Key type lgssc unregistered [ 2992.959681] LNet: 71606:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2992.967926] LNetError: 71606:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2992.978590] LNet: Removed LNI 192.168.203.12@tcp [ 2993.646305] Key type .llcrypt unregistered [ 2993.649676] Key type ._llcrypt unregistered [ 3010.483826] Key type ._llcrypt registered [ 3010.486230] Key type .llcrypt registered [ 3012.334647] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 17:44:52 (1786311892) [ 3015.519920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3015.597950] alg: No test for adler32 (adler32-zlib) [ 3016.755059] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3017.560204] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3019.264417] Key type lgssc registered [ 3020.781601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3070.214214] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3076.814535] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3100.038567] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3102.324677] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3109.938610] Lustre: Unmounted lustre-client [ 3110.639996] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3113.561836] Lustre: Unmounted lustre-client [ 3140.542411] Key type lgssc unregistered [ 3140.894880] LNet: 75293:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3140.899263] LNetError: 75293:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3140.911721] LNet: Removed LNI 192.168.203.12@tcp [ 3141.555378] Key type .llcrypt unregistered [ 3141.559745] Key type ._llcrypt unregistered [ 3156.362596] Key type ._llcrypt registered [ 3156.364294] Key type .llcrypt registered [ 3156.801427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3156.810674] alg: No test for adler32 (adler32-zlib) [ 3157.823149] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3158.235215] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3159.912416] Key type lgssc registered [ 3161.305637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3212.994608] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3218.516769] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3238.021308] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3240.739611] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3247.533374] Lustre: Unmounted lustre-client [ 3272.367951] Key type lgssc unregistered [ 3272.642558] LNet: 78325:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3272.649588] LNetError: 78325:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3272.670785] LNet: Removed LNI 192.168.203.12@tcp [ 3273.267400] Key type .llcrypt unregistered [ 3273.269391] Key type ._llcrypt unregistered [ 3288.445508] Key type ._llcrypt registered [ 3288.447952] Key type .llcrypt registered [ 3289.538949] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 17:49:30 (1786312170) [ 3292.917864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3292.937940] alg: No test for adler32 (adler32-zlib) [ 3294.086472] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3294.436178] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3296.064240] Key type lgssc registered [ 3297.058221] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3343.724028] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3349.483610] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3372.603205] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3374.358380] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3390.039409] Lustre: lustre: root_squash is set to 0:0 [ 3390.057914] Lustre: lustre: nosquash_nids is cleared [ 3395.598805] Lustre: lustre: root_squash is set to 500:500 [ 3405.337541] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.203.12@tcp 192.168.0.[2,10]@tcp [ 3414.541873] Lustre: lustre: nosquash_nids set to 192.168.203.12/24@tcp [ 3418.451313] Lustre: Unmounted lustre-client [ 3445.359948] Key type lgssc unregistered [ 3445.568851] LNet: 82282:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3445.579776] LNetError: 82282:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3445.590292] LNet: Removed LNI 192.168.203.12@tcp [ 3446.254076] Key type .llcrypt unregistered [ 3446.258561] Key type ._llcrypt unregistered [ 3465.277709] Key type ._llcrypt registered [ 3465.279491] Key type .llcrypt registered [ 3467.570679] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 17:52:27 (1786312347) [ 3469.879792] Lustre: DEBUG MARKER: SKIP: conf-sanity test_43b mixed loopback and real device not working [ 3471.871080] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 17:52:33 (1786312353) [ 3476.104163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3476.180256] alg: No test for adler32 (adler32-zlib) [ 3477.407231] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3477.695620] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3479.400240] Key type lgssc registered [ 3481.478388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3536.148922] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3541.985841] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3562.164836] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3563.821203] Lustre: lustre: root_squash is set to 500:500 [ 3563.847052] Lustre: lustre: nosquash_nids set to 192.168.203.12/24@tcp [ 3564.955684] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3573.229148] Lustre: Unmounted lustre-client [ 3600.243868] Key type lgssc unregistered [ 3600.434665] LNet: 86248:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3600.447901] LNetError: 86248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3600.482690] LNet: Removed LNI 192.168.203.12@tcp [ 3601.118277] Key type .llcrypt unregistered [ 3601.123256] Key type ._llcrypt unregistered [ 3618.514563] Key type ._llcrypt registered [ 3618.516266] Key type .llcrypt registered [ 3619.834570] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 3621.965711] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 17:55:02 (1786312502) [ 3640.360446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3640.420032] alg: No test for adler32 (adler32-zlib) [ 3641.620976] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3641.860429] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3643.520177] Key type lgssc registered [ 3644.936475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3727.963751] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3733.154714] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3752.551649] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3815.855913] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3822.000587] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3843.221175] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3854.509832] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3886.162501] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a4fc4c57000.ost_server_uuid 50 [ 3887.814441] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a4fc4c57000.ost_server_uuid in FULL state after 0 sec [ 3888.494818] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 3891.513807] Lustre: Unmounted lustre-client [ 3892.848952] Lustre: Unmounted lustre-client [ 3938.456499] Key type lgssc unregistered [ 3938.803838] LNet: 93910:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3938.824109] LNetError: 93910:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3938.842584] LNet: Removed LNI 192.168.203.12@tcp [ 3939.762422] Key type .llcrypt unregistered [ 3939.766522] Key type ._llcrypt unregistered [ 3968.693944] Key type ._llcrypt registered [ 3968.696313] Key type .llcrypt registered [ 3969.976441] Lustre: DEBUG MARKER: == conf-sanity test 47a: server restart does not lose lru_resize ========================================================== 18:00:50 (1786312850) [ 3988.459041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3988.598768] alg: No test for adler32 (adler32-zlib) [ 3989.663491] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 3989.837321] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 3991.480182] Key type lgssc registered [ 3992.592949] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4070.622543] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4076.660543] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4099.511569] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4102.763580] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 4124.192291] Lustre: 95282:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786312990/real 1786312990] req@00000000bd0354a4 x1873084806075648/t0(0) o400->lustre-OST0000-osc-ffff9a4fc954e800@192.168.203.112@tcp:28/4 lens 224/224 e 0 to 1 dl 1786313006 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4124.223773] Lustre: lustre-OST0000-osc-ffff9a4fc954e800: Connection to lustre-OST0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4130.272230] Lustre: 95282:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786312995/real 1786312995] req@000000005dacfd4d x1873084806076160/t0(0) o400->lustre-OST0000-osc-ffff9a4fc954e800@192.168.203.112@tcp:28/4 lens 224/224 e 0 to 1 dl 1786313011 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4134.495841] Lustre: lustre-OST0000-osc-ffff9a4fc954e800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 4135.584994] Lustre: 95282:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786313000/real 1786313000] req@000000006a42cb22 x1873084806076672/t0(0) o400->lustre-OST0000-osc-ffff9a4fc954e800@192.168.203.112@tcp:28/4 lens 224/224 e 0 to 1 dl 1786313016 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4138.856327] Lustre: 95282:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786313005/real 1786313005] req@000000007358d9b2 x1873084806077184/t0(0) o400->lustre-OST0000-osc-ffff9a4fc954e800@192.168.203.112@tcp:28/4 lens 224/224 e 0 to 1 dl 1786313021 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4139.509779] Lustre: lustre-MDT0000-mdc-ffff9a4fc954e800: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4154.848269] Lustre: 95281:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786313021/real 1786313021] req@00000000cf6833a5 x1873084806079488/t0(0) o400->MGC192.168.203.112@tcp@192.168.203.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1786313037 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4154.910576] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 4165.102454] Lustre: Evicted from MGS (at 192.168.203.112@tcp) after server handle changed from 0x818449e9f5e3d5bf to 0x818449e9f5e3d851 [ 4165.112640] Lustre: MGC192.168.203.112@tcp: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 4169.672449] Lustre: lustre-MDT0000-mdc-ffff9a4fc954e800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 4172.925311] Lustre: Unmounted lustre-client [ 4209.640760] Key type lgssc unregistered [ 4209.934181] LNet: 99587:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4209.956847] LNetError: 99587:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4209.994798] LNet: Removed LNI 192.168.203.12@tcp [ 4210.733944] Key type .llcrypt unregistered [ 4210.735734] Key type ._llcrypt unregistered [ 4227.645795] Key type ._llcrypt registered [ 4227.648598] Key type .llcrypt registered [ 4229.767048] Lustre: DEBUG MARKER: == conf-sanity test 47b: client restart does not lose lru_resize ========================================================== 18:05:09 (1786313109) [ 4244.752744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4244.770832] alg: No test for adler32 (adler32-zlib) [ 4246.035789] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 4246.226818] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 4247.864276] Key type lgssc registered [ 4249.194804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4284.510736] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4375.031151] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4380.735881] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4403.483427] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4405.140459] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 4407.154223] Lustre: DEBUG MARKER: SKIP: conf-sanity test_47b Can not create symlink /usr/sbin/lctl [ 4409.103222] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 18:08:10 (1786313290) [ 4630.071314] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4640.454787] Lustre: Unmounted lustre-client [ 4757.435756] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4762.091117] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4784.038334] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4834.131685] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:15:15 (1786313715) [ 4926.575419] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4931.699336] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4954.276994] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4955.113344] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 4958.773431] Lustre: Unmounted lustre-client [ 4990.002521] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 18:17:51 (1786313871) [ 5079.722396] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5086.050226] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5108.970949] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5109.589602] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 5113.754534] Lustre: Unmounted lustre-client [ 5149.250779] Key type lgssc unregistered [ 5149.492815] LNet: 122604:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5149.502286] LNetError: 122604:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5149.519137] LNet: Removed LNI 192.168.203.12@tcp [ 5150.277950] Key type .llcrypt unregistered [ 5150.281604] Key type ._llcrypt unregistered [ 5164.922102] Key type ._llcrypt registered [ 5164.924745] Key type .llcrypt registered [ 5166.047463] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 18:20:47 (1786314047) [ 5168.702818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5168.832897] alg: No test for adler32 (adler32-zlib) [ 5169.830490] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 5170.142984] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 5171.785143] Key type lgssc registered [ 5173.051352] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5217.735353] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5223.069378] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5243.759401] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5246.390908] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 5259.706470] Lustre: Unmounted lustre-client [ 5287.704683] Key type lgssc unregistered [ 5288.274553] LNet: 126201:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5288.296045] LNetError: 126201:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5288.316428] LNet: Removed LNI 192.168.203.12@tcp [ 5289.009813] Key type .llcrypt unregistered [ 5289.011727] Key type ._llcrypt unregistered [ 5305.034105] Key type ._llcrypt registered [ 5305.039623] Key type .llcrypt registered [ 5306.971286] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 18:23:07 (1786314187) [ 5309.654425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5309.708528] alg: No test for adler32 (adler32-zlib) [ 5310.834527] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 5311.099932] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 5312.888260] Key type lgssc registered [ 5313.844313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5363.285248] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5368.795449] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5389.326131] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5391.706724] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 5402.122853] Lustre: lustre-OST0000-osc-ffff9a4fc788a000: Connection to lustre-OST0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5412.072529] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff9a4fc788a000.ost_server_uuid 50 [ 5413.521105] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 5424.664235] Lustre: Unmounted lustre-client [ 5444.582842] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 18:25:25 (1786314325) [ 5473.974245] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5479.748895] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5502.872983] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5528.445365] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5529.389552] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 5534.747343] Lustre: lustre-OST0000-osc-ffff9a4fc7ded000: Connection to lustre-OST0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5559.542172] Lustre: Unmounted lustre-client [ 5607.886711] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 18:28:08 (1786314488) [ 5639.673688] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5646.140449] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5668.503371] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5689.680355] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5690.828723] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 5695.977033] Lustre: lustre-OST0000-osc-ffff9a4fc4c9e800: Connection to lustre-OST0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5711.689935] Lustre: Unmounted lustre-client [ 5759.093229] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 18:30:40 (1786314640) [ 5846.758267] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5852.679612] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5874.919711] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5941.929343] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5948.950982] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5968.820339] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6006.996242] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6049.576991] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6053.348568] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 2 sec [ 6055.152585] Lustre: Unmounted lustre-client [ 6096.533315] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 18:36:17 (1786314977) [ 6127.719685] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6133.287297] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6156.682488] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6188.489944] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6218.355333] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6260.877893] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6262.350706] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6273.719407] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6284.138554] Lustre: Unmounted lustre-client [ 6336.981667] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 18:40:17 (1786315217) [ 6368.046483] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6374.099906] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6394.019347] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6394.780591] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6419.882780] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6421.666483] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6435.665799] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9a4fc9f9b800.ost_server_uuid 50 [ 6437.276228] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9a4fc9f9b800.ost_server_uuid in IDLE state after 0 sec [ 6439.949122] Lustre: Unmounted lustre-client [ 6440.294755] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 6440.307257] Lustre: Skipped 3 previous similar messages [ 6440.417311] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6441.824836] LustreError: 149635:0:(sec.c:403:import_sec_validate_get()) import 0000000072984e6a (NEW) with no sec [ 6503.065911] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 18:43:04 (1786315384) [ 6537.216049] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6542.918832] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6562.741969] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6584.189919] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6584.838472] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 6584.966659] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6596.990107] Lustre: Unmounted lustre-client [ 6597.016203] Lustre: Skipped 1 previous similar message [ 6649.855494] Key type lgssc unregistered [ 6650.184169] LNet: 153048:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6650.215683] LNetError: 153048:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6650.240748] LNet: Removed LNI 192.168.203.12@tcp [ 6650.820542] Key type .llcrypt unregistered [ 6650.822425] Key type ._llcrypt unregistered [ 6664.773704] Key type ._llcrypt registered [ 6664.775740] Key type .llcrypt registered [ 6665.965991] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 18:45:46 (1786315546) [ 6667.335367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6667.456454] alg: No test for adler32 (adler32-zlib) [ 6668.702233] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 6668.880159] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 6670.552187] Key type lgssc registered [ 6671.926659] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6717.246746] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6722.614318] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6742.131291] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6761.152833] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6762.662543] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6763.836768] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6770.254785] LustreError: lustre-MDT0001-mdc-ffff9a4fd1464800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6770.276469] Lustre: lustre-MDT0001-mdc-ffff9a4fd1464800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 6777.155302] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6780.318756] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 6800.898226] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [ 6803.519613] Lustre: Unmounted lustre-client [ 6834.528603] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 18:48:35 (1786315715) [ 6919.643232] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6925.581924] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6946.006353] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6946.514397] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 6972.020067] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6973.520441] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 6975.995449] Lustre: lustre-OST0001-osc-ffff9a4fc776d000: Connection to lustre-OST0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6984.086532] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 6994.507877] Lustre: Unmounted lustre-client [ 7027.192908] Key type lgssc unregistered [ 7027.437379] LNet: 162670:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7027.444351] LNetError: 162670:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7027.492328] LNet: Removed LNI 192.168.203.12@tcp [ 7028.300178] Key type .llcrypt unregistered [ 7028.303192] Key type ._llcrypt unregistered [ 7061.642359] Key type ._llcrypt registered [ 7061.644865] Key type .llcrypt registered [ 7063.060489] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 18:52:23 (1786315943) [ 7066.093394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7066.144441] alg: No test for adler32 (adler32-zlib) [ 7067.278789] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7067.526045] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7069.168175] Key type lgssc registered [ 7070.379916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7120.834317] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7127.243903] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7149.783274] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7151.654273] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7195.859767] Lustre: Unmounted lustre-client [ 7235.748441] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7236.842955] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7273.690400] Lustre: Unmounted lustre-client [ 7303.548954] Key type lgssc unregistered [ 7304.254157] LNet: 167121:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7304.262866] LNetError: 167121:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7304.282159] LNet: Removed LNI 192.168.203.12@tcp [ 7305.182352] Key type .llcrypt unregistered [ 7305.184651] Key type ._llcrypt unregistered [ 7324.049270] Key type ._llcrypt registered [ 7324.051837] Key type .llcrypt registered [ 7325.609204] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 18:56:46 (1786316206) [ 7328.020758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7328.046510] alg: No test for adler32 (adler32-zlib) [ 7329.818475] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7330.251556] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7331.954211] Key type lgssc registered [ 7334.181588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7385.726924] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7392.251085] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7414.131796] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7416.062658] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7436.500091] Lustre: Unmounted lustre-client [ 7463.134730] Key type lgssc unregistered [ 7463.315925] LNet: 170884:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7463.328988] LNetError: 170884:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7463.345722] LNet: Removed LNI 192.168.203.12@tcp [ 7464.241339] Key type .llcrypt unregistered [ 7464.250790] Key type ._llcrypt unregistered [ 7477.812840] Key type ._llcrypt registered [ 7477.814899] Key type .llcrypt registered [ 7478.558581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7478.572359] alg: No test for adler32 (adler32-zlib) [ 7479.640545] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7480.747450] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7482.384217] Key type lgssc registered [ 7483.836474] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7534.142663] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7540.517416] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7561.678559] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7564.977981] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7575.727182] Lustre: Unmounted lustre-client [ 7603.152454] Key type lgssc unregistered [ 7603.387736] LNet: 173968:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7603.393399] LNetError: 173968:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7603.436554] LNet: Removed LNI 192.168.203.12@tcp [ 7604.044506] Key type .llcrypt unregistered [ 7604.053726] Key type ._llcrypt unregistered [ 7622.095509] Key type ._llcrypt registered [ 7622.097621] Key type .llcrypt registered [ 7623.598551] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 19:01:44 (1786316504) [ 7627.712829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7627.755493] alg: No test for adler32 (adler32-zlib) [ 7628.833537] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7629.039406] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7630.880385] Key type lgssc registered [ 7632.072608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7680.946570] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7686.790948] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7709.169456] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7712.486459] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7731.282105] Lustre: Unmounted lustre-client [ 7760.298316] Key type lgssc unregistered [ 7760.527083] LNet: 177726:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7760.536125] LNetError: 177726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7760.551277] LNet: Removed LNI 192.168.203.12@tcp [ 7761.439548] Key type .llcrypt unregistered [ 7761.441296] Key type ._llcrypt unregistered [ 7777.003195] Key type ._llcrypt registered [ 7777.008229] Key type .llcrypt registered [ 7777.679742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7777.690894] alg: No test for adler32 (adler32-zlib) [ 7779.400472] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7779.964529] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7781.781925] Key type lgssc registered [ 7782.809442] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7833.917883] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7840.852566] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7862.724844] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7865.599415] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 7875.661101] Lustre: Unmounted lustre-client [ 7904.576799] Key type lgssc unregistered [ 7904.847414] LNet: 180796:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7904.852182] LNetError: 180796:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7904.863174] LNet: Removed LNI 192.168.203.12@tcp [ 7905.542355] Key type .llcrypt unregistered [ 7905.544664] Key type ._llcrypt unregistered [ 7923.036379] Key type ._llcrypt registered [ 7923.038277] Key type .llcrypt registered [ 7925.186870] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 19:06:45 (1786316805) [ 7950.770987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7950.792649] alg: No test for adler32 (adler32-zlib) [ 7952.055770] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 7952.379680] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 7954.238376] Key type lgssc registered [ 7955.506585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8036.038673] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8042.180625] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8067.082612] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8108.961834] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 19:09:49 (1786316989) [ 8139.517338] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8145.176370] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8166.002709] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8168.670791] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 8173.057100] Lustre: Unmounted lustre-client [ 8200.395980] Key type lgssc unregistered [ 8200.738819] LNet: 187907:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8200.751052] LNetError: 187907:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8200.774670] LNet: Removed LNI 192.168.203.12@tcp [ 8201.403724] Key type .llcrypt unregistered [ 8201.405672] Key type ._llcrypt unregistered [ 8217.420081] Key type ._llcrypt registered [ 8217.422707] Key type .llcrypt registered [ 8218.706226] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 8220.142735] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 19:11:41 (1786317101) [ 8238.966870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8238.983418] alg: No test for adler32 (adler32-zlib) [ 8239.950393] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 8240.161647] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 8241.794688] Key type lgssc registered [ 8242.614519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8328.832326] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8334.311350] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8355.874897] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8377.411300] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8380.407505] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 8402.309090] Lustre: Unmounted lustre-client [ 8526.764367] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8531.553602] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8551.941229] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8591.779710] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 19:17:52 (1786317472) [ 8593.172961] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 8594.911716] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 19:17:55 (1786317475) [ 8686.213504] Key type lgssc unregistered [ 8686.415213] LNet: 199345:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8686.427650] LNetError: 199345:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8686.445318] LNet: Removed LNI 192.168.203.12@tcp [ 8687.120869] Key type .llcrypt unregistered [ 8687.122951] Key type ._llcrypt unregistered [ 8703.945902] Key type ._llcrypt registered [ 8703.947677] Key type .llcrypt registered [ 8705.423684] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 19:19:46 (1786317586) [ 8748.630497] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8748.872424] alg: No test for adler32 (adler32-zlib) [ 8749.892671] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 8750.081377] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 8751.752243] Key type lgssc registered [ 8752.926490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8796.010552] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8801.656614] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8835.520262] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8871.847761] Key type lgssc unregistered [ 8872.066683] LNet: 203080:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8872.072308] LNetError: 203080:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8872.081232] LNet: Removed LNI 192.168.203.12@tcp [ 8872.966662] Key type .llcrypt unregistered [ 8872.968740] Key type ._llcrypt unregistered [ 8889.837758] Key type ._llcrypt registered [ 8889.840668] Key type .llcrypt registered [ 8892.203090] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 19:22:51 (1786317771) [ 8895.424521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8895.546171] alg: No test for adler32 (adler32-zlib) [ 8896.805453] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 8897.005661] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 8898.720448] Key type lgssc registered [ 8900.021782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8947.559521] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8953.446961] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8974.639412] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8977.139472] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 8992.751296] Lustre: lustre-MDT0000-mdc-ffff9a4fc9e52800: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9008.233435] Lustre: lustre-MDT0001-mdc-ffff9a4fc9e52800: Connection to lustre-MDT0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9024.481130] Lustre: 204071:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786317890/real 1786317890] req@00000000c06d4543 x1873089951516288/t0(0) o400->MGC192.168.203.112@tcp@192.168.203.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1786317906 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9024.502154] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 9049.085850] Lustre: Evicted from MGS (at 192.168.203.112@tcp) after server handle changed from 0x72977511a18c5cfb to 0x72977511a18c780d [ 9049.103044] Lustre: MGC192.168.203.112@tcp: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9072.733193] LustreError: lustre-MDT0000-mdc-ffff9a4fc9e52800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9072.762755] Lustre: lustre-MDT0000-mdc-ffff9a4fc9e52800: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9077.964474] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9079.302238] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9085.051605] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9086.898856] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9089.587600] Lustre: Unmounted lustre-client [ 9123.575672] Key type lgssc unregistered [ 9123.802044] LNet: 208032:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9123.806622] LNetError: 208032:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9123.822641] LNet: Removed LNI 192.168.203.12@tcp [ 9124.406953] Key type .llcrypt unregistered [ 9124.409283] Key type ._llcrypt unregistered [ 9140.896807] Key type ._llcrypt registered [ 9140.899510] Key type .llcrypt registered [ 9142.532025] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 19:27:03 (1786318023) [ 9146.161938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9146.216353] alg: No test for adler32 (adler32-zlib) [ 9147.234484] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 9147.481369] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 9149.120204] Key type lgssc registered [ 9149.987512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9198.169962] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9204.569420] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9252.854351] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9258.091774] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9292.812603] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9314.433142] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9358.465349] Key type lgssc unregistered [ 9358.762678] LNet: 213327:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9358.767393] LNetError: 213327:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9358.778718] LNet: Removed LNI 192.168.203.12@tcp [ 9359.410491] Key type .llcrypt unregistered [ 9359.414315] Key type ._llcrypt unregistered [ 9399.586820] Key type ._llcrypt registered [ 9399.592961] Key type .llcrypt registered [ 9401.238499] Lustre: DEBUG MARKER: == conf-sanity test 60a: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 19:31:21 (1786318281) [ 9439.032258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9439.052349] alg: No test for adler32 (adler32-zlib) [ 9440.216321] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 9440.426265] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 9442.088703] Key type lgssc registered [ 9443.107484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9517.852254] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9523.320088] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9545.943316] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9586.762256] Lustre: DEBUG MARKER: == conf-sanity test 60b: check mkfs.lustre MDT default features ========================================================== 19:34:27 (1786318467) [ 9595.099266] Lustre: DEBUG MARKER: == conf-sanity test 61a: large xattr ===================== 19:34:35 (1786318475) [ 9629.574679] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9635.852730] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9655.996288] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9658.706221] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 9668.320833] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61a.conf-sanity [ 9670.199917] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9672.087579] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61a.conf-sanity [ 9673.473117] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61a.conf-sanity after remounting MDS [ 9679.335908] Lustre: lustre-MDT0000-mdc-ffff9a4fc776d000: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9694.688210] Lustre: 215222:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786318561/real 1786318561] req@000000003dc1f91a x1873090520819840/t0(0) o400->MGC192.168.203.112@tcp@192.168.203.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1786318577 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9694.714959] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 9704.995463] Lustre: Evicted from MGS (at 192.168.203.112@tcp) after server handle changed from 0x8ed71073aa5b082f to 0x8ed71073aa5b0aa5 [ 9705.009653] Lustre: MGC192.168.203.112@tcp: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9709.552484] Lustre: lustre-MDT0000-mdc-ffff9a4fc776d000: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9718.996795] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9720.511386] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9723.793300] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61a.conf-sanity [ 9730.532533] Lustre: lustre-MDT0000-mdc-ffff9a4fc776d000: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9745.830352] Lustre: 215222:0:(client.c:2490:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1786318612/real 1786318612] req@00000000bf42ef8f x1873090520828800/t0(0) o400->MGC192.168.203.112@tcp@192.168.203.112@tcp:26/25 lens 224/224 e 0 to 1 dl 1786318628 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 9745.854640] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [ 9756.186374] Lustre: Evicted from MGS (at 192.168.203.112@tcp) after server handle changed from 0x8ed71073aa5b0aa5 to 0x8ed71073aa5b0db5 [ 9756.206891] Lustre: MGC192.168.203.112@tcp: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9761.239971] Lustre: lustre-MDT0000-mdc-ffff9a4fc776d000: Connection restored to 192.168.203.112@tcp (at 192.168.203.112@tcp) [ 9770.158295] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9771.911401] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 9775.500104] Lustre: Unmounted lustre-client [ 9843.795863] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9849.580584] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9870.083352] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9870.792940] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [ 9872.763134] Lustre: Unmounted lustre-client [ 9909.205357] Key type lgssc unregistered [ 9909.430405] LNet: 225813:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9909.437469] LNetError: 225813:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9910.503591] LNet: Removed LNI 192.168.203.12@tcp [ 9911.356666] Key type .llcrypt unregistered [ 9911.358796] Key type ._llcrypt unregistered [ 9927.577545] Key type ._llcrypt registered [ 9927.580210] Key type .llcrypt registered [ 9929.082652] Lustre: DEBUG MARKER: == conf-sanity test 61b: large xattr ===================== 19:40:09 (1786318809) [ 9934.371085] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9934.394670] alg: No test for adler32 (adler32-zlib) [ 9935.764927] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [ 9936.057643] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [ 9937.724731] Key type lgssc registered [ 9938.908827] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9987.273584] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9992.677271] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10014.824248] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10017.647678] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [10023.443336] Lustre: Unmounted lustre-client [10087.913661] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10093.976383] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10114.265676] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10115.028812] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [10123.080166] Lustre: Unmounted lustre-client [10194.430980] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10200.564090] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10223.800897] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10224.515879] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [10226.306349] Lustre: Unmounted lustre-client [10262.256853] Key type lgssc unregistered [10262.529356] LNet: 232855:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10262.552101] LNetError: 232855:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10262.575543] LNet: Removed LNI 192.168.203.12@tcp [10263.635402] Key type .llcrypt unregistered [10263.637818] Key type ._llcrypt unregistered [10282.861471] Key type ._llcrypt registered [10282.863499] Key type .llcrypt registered [10284.188414] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 19:46:05 (1786319165) [10288.537695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10288.668892] alg: No test for adler32 (adler32-zlib) [10289.812476] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [10290.011421] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [10291.648300] Key type lgssc registered [10292.571229] Lustre: Echo OBD driver; http://www.lustre.org/ [10338.037936] Key type lgssc unregistered [10338.312299] LNet: 235180:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10338.320281] LNetError: 235180:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10338.340283] LNet: Removed LNI 192.168.203.12@tcp [10339.034890] Key type .llcrypt unregistered [10339.038285] Key type ._llcrypt unregistered [10369.288962] Key type ._llcrypt registered [10369.290603] Key type .llcrypt registered [10369.832273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10369.923711] alg: No test for adler32 (adler32-zlib) [10370.889620] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [10371.051331] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [10372.692160] Key type lgssc registered [10373.620877] Lustre: Echo OBD driver; http://www.lustre.org/ [10456.278644] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10462.095578] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10486.811805] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10528.095647] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [10529.810967] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 19:50:10 (1786319410) [10561.559364] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10566.861582] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10589.513804] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10611.900115] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10613.550206] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [10618.860419] Lustre: lustre-OST0001-osc-ffff9a4fd151b000: Connection to lustre-OST0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [10624.717557] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [10635.319905] Lustre: Unmounted lustre-client [10667.771770] Key type lgssc unregistered [10668.077821] LNet: 242305:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10668.086436] LNetError: 242305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10668.113186] LNet: Removed LNI 192.168.203.12@tcp [10668.910386] Key type .llcrypt unregistered [10668.916546] Key type ._llcrypt unregistered [10698.273969] Key type ._llcrypt registered [10698.276024] Key type .llcrypt registered [10699.900744] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 19:53:00 (1786319580) [10713.893136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10713.921069] alg: No test for adler32 (adler32-zlib) [10715.406330] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [10715.784558] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [10717.467662] Key type lgssc registered [10718.724623] Lustre: Echo OBD driver; http://www.lustre.org/ [10772.596286] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10777.975764] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10801.810367] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 19:54:42 (1786319682) [10832.209201] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10838.152314] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10857.198375] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10860.158559] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [10873.473777] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [10879.903212] Lustre: Unmounted lustre-client [10982.050754] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10987.171334] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11041.234818] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11041.744864] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11044.105363] Lustre: Unmounted lustre-client [11080.797809] Key type lgssc unregistered [11081.038317] LNet: 250040:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11081.051945] LNetError: 250040:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11081.080338] LNet: Removed LNI 192.168.203.12@tcp [11081.940992] Key type .llcrypt unregistered [11081.945973] Key type ._llcrypt unregistered [11110.660240] Key type ._llcrypt registered [11110.668403] Key type .llcrypt registered [11110.918061] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11110.937781] alg: No test for adler32 (adler32-zlib) [11112.241386] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [11112.576800] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [11114.272239] Key type lgssc registered [11115.550267] Lustre: Echo OBD driver; http://www.lustre.org/ [11196.311782] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11202.267618] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11227.700668] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11271.859577] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [11273.341134] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 20:02:34 (1786320154) [11306.303163] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11312.231580] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11332.602501] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11337.331448] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11345.492379] Lustre: Unmounted lustre-client [11373.115386] Key type lgssc unregistered [11373.313862] LNet: 256689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11373.318542] LNetError: 256689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11373.329577] LNet: Removed LNI 192.168.203.12@tcp [11373.937662] Key type .llcrypt unregistered [11373.940238] Key type ._llcrypt unregistered [11392.576673] Key type ._llcrypt registered [11392.578544] Key type .llcrypt registered [11394.782493] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [11397.349494] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 20:04:37 (1786320277) [11407.234571] Key type .llcrypt unregistered [11407.236200] Key type ._llcrypt unregistered [11418.357742] Key type ._llcrypt registered [11418.379535] Key type .llcrypt registered [11419.318705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11419.329377] alg: No test for adler32 (adler32-zlib) [11420.479320] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [11420.722269] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [11422.528746] Key type lgssc registered [11423.418719] Lustre: Echo OBD driver; http://www.lustre.org/ [11473.746784] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11491.155974] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11493.139307] Lustre: Unmounted lustre-client [11521.743740] Key type lgssc unregistered [11522.047365] LNet: 260161:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11522.055242] LNetError: 260161:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11522.068596] LNet: Removed LNI 192.168.203.12@tcp [11522.651449] Key type .llcrypt unregistered [11522.656398] Key type ._llcrypt unregistered [11541.732174] Key type ._llcrypt registered [11541.736751] Key type .llcrypt registered [11542.837516] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 20:07:03 (1786320423) [11545.562275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11545.570634] alg: No test for adler32 (adler32-zlib) [11546.880247] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [11547.045164] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [11548.680192] Key type lgssc registered [11549.579689] Lustre: Echo OBD driver; http://www.lustre.org/ [11633.200396] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11672.380967] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11679.685448] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11683.227676] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11686.028352] Lustre: Unmounted lustre-client [11722.138801] Key type lgssc unregistered [11722.313836] LNet: 263709:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11722.318301] LNetError: 263709:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11722.332430] LNet: Removed LNI 192.168.203.12@tcp [11723.040221] Key type .llcrypt unregistered [11723.042207] Key type ._llcrypt unregistered [11739.869530] Key type ._llcrypt registered [11739.871445] Key type .llcrypt registered [11741.674550] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 20:10:22 (1786320622) [11744.494969] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11744.858049] alg: No test for adler32 (adler32-zlib) [11745.952753] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [11746.165036] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [11747.995669] Key type lgssc registered [11749.661941] Lustre: Echo OBD driver; http://www.lustre.org/ [11800.557379] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11805.995459] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11828.539785] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11832.095799] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11837.423671] Lustre: lustre-MDT0000-mdc-ffff9a4fc7dee000: Connection to lustre-MDT0000 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11840.557232] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [11840.613033] LustreError: 266870:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11841.127899] LustreError: 266889:0:(file.c:6154:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [11841.133944] LustreError: 266889:0:(file.c:6154:ll_inode_revalidate_fini()) Skipped 5 previous similar messages [11841.197373] LustreError: MGC192.168.203.112@tcp: Connection to MGS (at 192.168.203.112@tcp) was lost; in progress operations using this service will fail [11841.339520] Lustre: Unmounted lustre-client [11858.519878] Key type lgssc unregistered [11858.796559] LNet: 267286:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11858.801265] LNetError: 267286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11858.813224] LNet: Removed LNI 192.168.203.12@tcp [11859.614783] Key type .llcrypt unregistered [11859.617170] Key type ._llcrypt unregistered [11875.378771] Key type ._llcrypt registered [11875.381203] Key type .llcrypt registered [11876.795527] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 20:12:37 (1786320757) [11879.453901] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11879.487589] alg: No test for adler32 (adler32-zlib) [11880.924043] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [11881.114350] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [11882.776178] Key type lgssc registered [11883.898538] Lustre: Echo OBD driver; http://www.lustre.org/ [11933.008951] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [11938.370516] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [11959.949302] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [11962.820474] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [11967.998777] Lustre: lustre-MDT0001-mdc-ffff9a4fc7ded800: Connection to lustre-MDT0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11972.692774] Lustre: setting import lustre-MDT0001_UUID INACTIVE by administrator request [11974.324035] Lustre: Unmounted lustre-client [11993.601449] Key type lgssc unregistered [11993.836516] LNet: 270870:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11993.846409] LNetError: 270870:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [11993.869691] LNet: Removed LNI 192.168.203.12@tcp [11994.447653] Key type .llcrypt unregistered [11994.450108] Key type ._llcrypt unregistered [12010.694706] Key type ._llcrypt registered [12010.698185] Key type .llcrypt registered [12011.893707] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 20:14:52 (1786320892) [12029.293066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12029.304499] alg: No test for adler32 (adler32-zlib) [12030.430467] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [12030.669991] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [12032.395249] Key type lgssc registered [12033.508381] Lustre: Echo OBD driver; http://www.lustre.org/ [12137.260727] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12140.039464] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [12176.944885] Lustre: lustre-MDT0001-mdc-ffff9a4fc6b3b800: Connection to lustre-MDT0001 (at 192.168.203.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [12186.810759] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [12197.248575] Lustre: Unmounted lustre-client [12222.864813] Key type lgssc unregistered [12223.221266] LNet: 276660:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12223.226424] LNetError: 276660:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12223.249294] LNet: Removed LNI 192.168.203.12@tcp [12223.855471] Key type .llcrypt unregistered [12223.857154] Key type ._llcrypt unregistered [12240.066949] Key type ._llcrypt registered [12240.069168] Key type .llcrypt registered [12241.242123] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 20:18:42 (1786321122) [12242.752769] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs separate MGS/MDT [12244.625931] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 20:18:45 (1786321125) [12246.588863] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs separate MGS/MDT [12248.286762] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 20:18:49 (1786321129) [12250.284623] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs separate MGS/MDT [12252.871084] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 20:18:53 (1786321133) [12255.227734] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs separate MGS/MDT [12257.441514] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 20:18:57 (1786321137) [12259.461975] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs separate MGS/MDT [12262.987401] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 20:19:02 (1786321142) [12272.465509] Key type .llcrypt unregistered [12272.467558] Key type ._llcrypt unregistered [12279.852478] Key type ._llcrypt registered [12279.869614] Key type .llcrypt registered [12280.550992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12280.565881] alg: No test for adler32 (adler32-zlib) [12281.684501] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [12281.926875] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [12283.920266] Key type lgssc registered [12285.254540] Lustre: Echo OBD driver; http://www.lustre.org/ [12358.656142] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12363.757653] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12386.176625] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12389.069441] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [12391.249398] Lustre: Unmounted lustre-client [12430.579882] Lustre: DEBUG MARKER: == conf-sanity test 73a: failnode to update from mountdata properly ========================================================== 20:21:50 (1786321310) [12462.632884] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12469.301301] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12502.209899] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12513.390732] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [12515.064454] Lustre: Unmounted lustre-client [12558.112493] LNet: 1 local NIs in recovery (showing 1): 192.168.203.12@tcp [12565.868800] Lustre: DEBUG MARKER: == conf-sanity test 73b: Large failnode NID list in mountdata ========================================================== 20:24:07 (1786321447) [13211.783345] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13217.887397] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13251.979298] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13347.882535] Lustre: DEBUG MARKER: == conf-sanity test 73c: Server mount doesn't fail with > 32 nets ========================================================== 20:37:08 (1786322228) [13361.619154] Key type lgssc unregistered [13361.834710] LNet: 290205:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13361.839996] LNetError: 290205:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13361.857304] LNet: Removed LNI 192.168.203.12@tcp [13362.954689] Key type .llcrypt unregistered [13362.959792] Key type ._llcrypt unregistered [13502.824689] Key type ._llcrypt registered [13502.826315] Key type .llcrypt registered [13503.302929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13503.312269] alg: No test for adler32 (adler32-zlib) [13504.284293] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [13504.471836] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [13506.232670] Key type lgssc registered [13507.161868] Lustre: Echo OBD driver; http://www.lustre.org/ [13595.435898] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13603.525182] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13628.011451] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13658.725978] Key type lgssc unregistered [13659.031530] LNet: 293913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13659.041906] LNetError: 293913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13659.056267] LNet: Removed LNI 192.168.203.12@tcp [13659.722181] Key type .llcrypt unregistered [13659.727764] Key type ._llcrypt unregistered [13696.312303] Key type ._llcrypt registered [13696.315625] Key type .llcrypt registered [13698.279575] Lustre: DEBUG MARKER: == conf-sanity test 73d: erase + new parameter doesn't corrupt mountdata ========================================================== 20:42:58 (1786322578) [13719.261663] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13719.274104] alg: No test for adler32 (adler32-zlib) [13720.270100] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [13720.490583] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [13722.336195] Key type lgssc registered [13723.666746] Lustre: Echo OBD driver; http://www.lustre.org/ [13773.876855] Lustre: DEBUG MARKER: == conf-sanity test 73e: Mount client with dynamic server NIDs ========================================================== 20:44:14 (1786322654) [13784.544484] Key type lgssc unregistered [13784.767948] LNet: 297474:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13784.775557] LNetError: 297474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13784.786518] LNet: Removed LNI 192.168.203.12@tcp [13785.397767] Key type .llcrypt unregistered [13785.402375] Key type ._llcrypt unregistered [13796.229852] Key type ._llcrypt registered [13796.233801] Key type .llcrypt registered [13797.094510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13797.106472] alg: No test for adler32 (adler32-zlib) [13798.126395] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [13798.299054] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [13800.008173] Key type lgssc registered [13801.040821] Lustre: Echo OBD driver; http://www.lustre.org/ [13850.427958] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13855.295833] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13876.462630] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14141.539917] LNet: Added LNI 192.168.203.12@tcp50 [8/256/0/180] [14143.806368] LNet: Removed LNI 192.168.203.12@tcp [14145.833581] Lustre: 301865:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9a4fc713e800: config has no valid NIDs, force dynamic NIDs [14147.090252] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [14147.288974] Lustre: Unmounted lustre-client [14150.182285] Key type lgssc unregistered [14150.427924] LNet: 302127:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14150.442989] LNetError: 302127:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14150.468949] LNet: Removed LNI 192.168.203.12@tcp50 [14151.274861] Key type .llcrypt unregistered [14151.282160] Key type ._llcrypt unregistered [14168.854135] Key type ._llcrypt registered [14168.855821] Key type .llcrypt registered [14169.100863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14169.122925] alg: No test for adler32 (adler32-zlib) [14170.104377] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [14170.370922] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [14172.072823] Key type lgssc registered [14173.169958] Lustre: Echo OBD driver; http://www.lustre.org/ [14234.085899] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14240.839860] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14248.667810] LNet: Added LNI 192.168.203.12@tcp50 [8/256/0/180] [14249.590769] LNet: Removed LNI 192.168.203.12@tcp [14250.819065] Lustre: 304818:0:(ldlm_lib.c:563:client_obd_setup()) lustre-MDT0000-mdc-ffff9a4fc6cbe000: config has no valid NIDs, force dynamic NIDs [14252.049849] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [14252.583375] Lustre: Unmounted lustre-client [14294.641843] Key type lgssc unregistered [14294.988245] LNet: 305692:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14294.992954] LNetError: 305692:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14295.023627] LNet: Removed LNI 192.168.203.12@tcp50 [14295.690739] Key type .llcrypt unregistered [14295.692773] Key type ._llcrypt unregistered [14306.435441] Key type ._llcrypt registered [14306.437457] Key type .llcrypt registered [14307.549929] Lustre: DEBUG MARKER: == conf-sanity test 73f: mgc register before connect ===== 20:53:08 (1786323188) [14325.120944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14325.162397] alg: No test for adler32 (adler32-zlib) [14326.204948] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [14326.455293] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [14328.137091] Key type lgssc registered [14329.405271] Lustre: Echo OBD driver; http://www.lustre.org/ [14407.490608] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14413.253516] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14437.059626] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14469.742655] Key type lgssc unregistered [14470.008574] LNet: 310440:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14470.013386] LNetError: 310440:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14470.034439] LNet: Removed LNI 192.168.203.12@tcp [14470.658820] Key type .llcrypt unregistered [14470.663950] Key type ._llcrypt unregistered [14481.365085] Key type ._llcrypt registered [14481.366979] Key type .llcrypt registered [14482.900539] Lustre: DEBUG MARKER: == conf-sanity test 74: Test per-device adaptive timeout parameters ========================================================== 20:56:03 (1786323363) [14485.047375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14485.148301] alg: No test for adler32 (adler32-zlib) [14486.226589] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [14486.398578] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [14488.056383] Key type lgssc registered [14489.203535] Lustre: Echo OBD driver; http://www.lustre.org/ [14535.008364] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14539.817551] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14561.432289] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14563.187577] Lustre: Mounted lustre-client - version 2.17.56_55_g704561f [14610.156675] Lustre: Mounted fs15246-client - version 2.17.56_55_g704561f [14621.721331] Lustre: Unmounted fs15246-client [14644.509490] Lustre: Unmounted lustre-client [14678.847438] Key type lgssc unregistered [14679.091942] LNet: 315292:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14679.099778] LNetError: 315292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14679.114520] LNet: Removed LNI 192.168.203.12@tcp [14679.683339] Key type .llcrypt unregistered [14679.696873] Key type ._llcrypt unregistered [14692.333200] Key type ._llcrypt registered [14692.336836] Key type .llcrypt registered [14693.616426] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 20:59:34 (1786323574) [14695.117485] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [14695.516165] alg: No test for adler32 (adler32-zlib) [14696.641973] Lustre: Lustre: Build Version: 2.17.56_55_g704561f [14696.859706] LNet: Added LNI 192.168.203.12@tcp [8/256/0/180] [14698.624603] Key type lgssc registered [14700.107647] Lustre: Echo OBD driver; http://www.lustre.org/ [14814.548319] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14820.978245] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14844.221756] Lustre: DEBUG MARKER: oleg312-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14956.091116] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14676 sec ========= 21:03:56 (1786323836) [14957.984929] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 21:03:58 (1786323838) === [14961.919963] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 21:04:02 (1786323842) === [14992.467400] Key type lgssc unregistered [14992.727101] LNet: 324564:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14992.735464] LNetError: 324564:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14992.754177] LNet: Removed LNI 192.168.203.12@tcp [14993.375816] Key type .llcrypt unregistered [14993.380864] Key type ._llcrypt unregistered