[ 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 458592630 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.002361] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.006936] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008012] pid_max: default: 32768 minimum: 301 [ 0.009132] LSM: Security Framework initializing [ 0.010057] Yama: becoming mindful. [ 0.011039] SELinux: Initializing. [ 0.013080] *** VALIDATE selinux *** [ 0.021440] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026319] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027163] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028126] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029115] *** VALIDATE tmpfs *** [ 0.031092] *** VALIDATE proc *** [ 0.032262] *** VALIDATE cgroup *** [ 0.033013] *** VALIDATE cgroup2 *** [ 0.034282] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035152] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037031] Spectre V2 : User space: Vulnerable [ 0.038009] Speculative Store Bypass: Vulnerable [ 0.041537] debug: unmapping init [mem 0xffffffffa9e59000-0xffffffffa9e60fff] [ 0.043183] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044644] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045032] ... version: 2 [ 0.046016] ... bit width: 48 [ 0.047014] ... generic registers: 4 [ 0.048011] ... value mask: 0000ffffffffffff [ 0.049016] ... max period: 00007fffffffffff [ 0.050014] ... fixed-purpose events: 3 [ 0.051010] ... event mask: 000000070000000f [ 0.052334] rcu: Hierarchical SRCU implementation. [ 0.054452] smp: Bringing up secondary CPUs ... [ 0.055623] x86: Booting SMP configuration: [ 0.056033] .... node #0, CPUs: #1 #2 #3 [ 0.059314] smp: Brought up 1 node, 4 CPUs [ 0.061013] smpboot: Max logical packages: 1 [ 0.062024] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.148553] node 0 deferred pages initialised in 84ms [ 0.153012] devtmpfs: initialized [ 0.154224] x86/mm: Memory block size: 128MB [ 0.156560] gcov: version magic: 0x41383552 [ 0.158349] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.161108] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.164329] pinctrl core: initialized pinctrl subsystem [ 0.166172] [ 0.166775] ************************************************************* [ 0.169016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.171010] ** ** [ 0.173012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.175011] ** ** [ 0.177012] ** This means that this kernel is built to expose internal ** [ 0.179012] ** IOMMU data structures, which may compromise security on ** [ 0.181011] ** your system. ** [ 0.184015] ** ** [ 0.186023] ** If you see this message and you are not debugging the ** [ 0.188013] ** kernel, report this immediately to your vendor! ** [ 0.190015] ** ** [ 0.192016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.194013] ************************************************************* [ 0.196680] NET: Registered protocol family 16 [ 0.198431] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.201081] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.203060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.206419] cpuidle: using governor menu [ 0.207725] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.210547] PCI: Using configuration type 1 for base access [ 0.211132] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.221057] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.223024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.227035] cryptd: max_cpu_qlen set to 1000 [ 0.230321] ACPI: Added _OSI(Module Device) [ 0.232019] ACPI: Added _OSI(Processor Device) [ 0.233011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.235030] ACPI: Added _OSI(Processor Aggregator Device) [ 0.239049] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.244423] ACPI: Interpreter enabled [ 0.246054] ACPI: PM: (supports S0 S3 S4 S5) [ 0.247013] ACPI: Using IOAPIC for interrupt routing [ 0.249100] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.252391] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.261443] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.264047] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.266017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.269079] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.273437] acpiphp: Slot [2] registered [ 0.275145] acpiphp: Slot [5] registered [ 0.277125] acpiphp: Slot [6] registered [ 0.278111] acpiphp: Slot [3] registered [ 0.279096] acpiphp: Slot [4] registered [ 0.280147] acpiphp: Slot [7] registered [ 0.282090] acpiphp: Slot [8] registered [ 0.283112] acpiphp: Slot [9] registered [ 0.284092] acpiphp: Slot [10] registered [ 0.285076] acpiphp: Slot [11] registered [ 0.286087] acpiphp: Slot [12] registered [ 0.288096] acpiphp: Slot [13] registered [ 0.289121] acpiphp: Slot [14] registered [ 0.290130] acpiphp: Slot [15] registered [ 0.292117] acpiphp: Slot [16] registered [ 0.293103] acpiphp: Slot [17] registered [ 0.294158] acpiphp: Slot [18] registered [ 0.297151] acpiphp: Slot [19] registered [ 0.299099] acpiphp: Slot [20] registered [ 0.300097] acpiphp: Slot [21] registered [ 0.302102] acpiphp: Slot [22] registered [ 0.303102] acpiphp: Slot [23] registered [ 0.304097] acpiphp: Slot [24] registered [ 0.306124] acpiphp: Slot [25] registered [ 0.307094] acpiphp: Slot [26] registered [ 0.309098] acpiphp: Slot [27] registered [ 0.310105] acpiphp: Slot [28] registered [ 0.312099] acpiphp: Slot [29] registered [ 0.313090] acpiphp: Slot [30] registered [ 0.315097] acpiphp: Slot [31] registered [ 0.316067] PCI host bridge to bus 0000:00 [ 0.317017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.319021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.321020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.323021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.325023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.328023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.329174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.331945] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.335232] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.341481] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.346000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.348016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.352028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.354351] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.356735] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.360052] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.362844] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.366981] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.377779] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.382020] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.388236] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.396013] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.415018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.426015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.445034] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.458028] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.466017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.486021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.496586] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.499316] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.500247] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.502405] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.504214] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.509040] iommu: Default domain type: Passthrough [ 0.510433] SCSI subsystem initialized [ 0.511128] ACPI: bus type USB registered [ 0.512113] usbcore: registered new interface driver usbfs [ 0.514068] usbcore: registered new interface driver hub [ 0.515072] usbcore: registered new device driver usb [ 0.517213] pps_core: LinuxPPS API ver. 1 registered [ 0.519010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.521071] PTP clock support registered [ 0.524045] EDAC MC: Ver: 3.0.0 [ 0.525390] PCI: Using ACPI for IRQ routing [ 0.527793] NetLabel: Initializing [ 0.529012] NetLabel: domain hash size = 128 [ 0.530010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.532081] NetLabel: unlabeled traffic allowed by default [ 0.535039] vgaarb: loaded [ 0.536275] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.537012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.542115] clocksource: Switched to clocksource kvm-clock [ 0.651808] VFS: Disk quotas dquot_6.6.0 [ 0.652765] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.655133] *** VALIDATE ramfs *** [ 0.656391] *** VALIDATE hugetlbfs *** [ 0.657931] pnp: PnP ACPI init [ 0.660408] pnp: PnP ACPI: found 6 devices [ 0.685525] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.688927] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.691078] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.693271] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.696322] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.698781] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.701576] NET: Registered protocol family 2 [ 0.703979] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.708926] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.712800] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.717822] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.720366] TCP: Hash tables configured (established 65536 bind 65536) [ 0.722696] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.725780] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.728017] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.730311] NET: Registered protocol family 1 [ 0.731944] RPC: Registered named UNIX socket transport module. [ 0.733465] RPC: Registered udp transport module. [ 0.735156] RPC: Registered tcp transport module. [ 0.736808] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.738775] NET: Registered protocol family 44 [ 0.740391] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.742220] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.743604] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.745215] PCI: CLS 0 bytes, default 64 [ 0.746678] Unpacking initramfs... [ 2.134686] debug: unmapping init [mem 0xffff9249fcc64000-0xffff9249fffcffff] [ 2.138543] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.140629] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.143405] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.658196] Initialise system trusted keyrings [ 2.659277] Key type blacklist registered [ 2.660675] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.668136] zbud: loaded [ 2.670288] *** VALIDATE nfs *** [ 2.671047] *** VALIDATE nfs4 *** [ 2.672187] pstore: using deflate compression [ 2.674893] Platform Keyring initialized [ 2.754256] NET: Registered protocol family 38 [ 2.755945] Key type asymmetric registered [ 2.757062] Asymmetric key parser 'x509' registered [ 2.758277] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.760197] io scheduler mq-deadline registered [ 2.761391] io scheduler kyber registered [ 2.763168] io scheduler bfq registered [ 2.765112] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.767382] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.769113] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.771293] ACPI: Power Button [PWRF] [ 2.775450] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.780258] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.788193] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.813358] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.838823] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.842938] Non-volatile memory driver v1.3 [ 2.844301] Linux agpgart interface v0.103 [ 2.868398] virtio_blk virtio1: [vda] 149960 512-byte logical blocks (76.8 MB/73.2 MiB) [ 2.870248] vda: detected capacity change from 0 to 76779520 [ 2.884426] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.887509] vdb: detected capacity change from 0 to 1073741824 [ 2.894974] libphy: Fixed MDIO Bus: probed [ 2.899393] usbcore: registered new interface driver usbserial_generic [ 2.901106] usbserial: USB Serial support registered for generic [ 2.902634] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.906697] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.909450] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.912413] mousedev: PS/2 mouse device common for all mice [ 2.914798] rtc_cmos 00:05: RTC can wake from S4 [ 2.917410] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.918889] rtc_cmos 00:05: registered as rtc0 [ 2.922686] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.923501] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.926930] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.929432] intel_pstate: CPU model not supported [ 2.934537] hid: raw HID events driver (C) Jiri Kosina [ 2.937137] usbcore: registered new interface driver usbhid [ 2.939669] usbhid: USB HID core driver [ 2.941613] drop_monitor: Initializing network drop monitor service [ 2.944563] Initializing XFRM netlink socket [ 2.946789] NET: Registered protocol family 10 [ 2.950830] Segment Routing with IPv6 [ 2.953393] NET: Registered protocol family 17 [ 2.956094] mpls_gso: MPLS GSO support [ 2.963329] RAS: Correctable Errors collector initialized. [ 2.965082] AVX version of gcm_enc/dec engaged. [ 2.966377] AES CTR mode by8 optimization enabled [ 3.025680] sched_clock: Marking stable (3025661822, 0)->(3843862797, -818200975) [ 3.028190] registered taskstats version 1 [ 3.029430] Loading compiled-in X.509 certificates [ 3.030691] zswap: loaded using pool lzo/zbud [ 3.048465] Key type big_key registered [ 3.058318] Key type encrypted registered [ 3.059500] ima: No TPM chip found, activating TPM-bypass! [ 3.060585] ima: Allocated hash algorithm: sha1 [ 3.061616] ima: No architecture policies found [ 3.062664] evm: Initialising EVM extended attributes: [ 3.064315] evm: security.selinux [ 3.065275] evm: security.ima [ 3.065969] evm: security.capability [ 3.066786] evm: HMAC attrs: 0x1 [ 3.068421] rtc_cmos 00:05: setting system clock to 2026-09-14 16:31:11 UTC (1789403471) [ 3.072318] debug: unmapping init [mem 0xffffffffaae03000-0xffffffffaaffffff] [ 3.073995] debug: unmapping init [mem 0xffffffffa9b82000-0xffffffffa9e58fff] [ 3.080100] Write protecting the kernel read-only data: 28672k [ 3.082395] debug: unmapping init [mem 0xffffffffa8203000-0xffffffffa83fffff] [ 3.084442] debug: unmapping init [mem 0xffffffffa8b14000-0xffffffffa8bfffff] [ 3.115862] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.124540] systemd[1]: Detected virtualization kvm. [ 3.126267] systemd[1]: Detected architecture x86-64. [ 3.128575] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.155419] systemd[1]: No hostname configured. [ 3.157529] systemd[1]: Set hostname to . [ 3.159987] random: systemd: uninitialized urandom read (16 bytes read) [ 3.163279] systemd[1]: Initializing machine ID from random generator. [ 3.206308] random: ln: uninitialized urandom read (6 bytes read) [ 3.287467] random: systemd: uninitialized urandom read (16 bytes read) [ 3.290474] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.295620] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.301989] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. 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... [ 3.828988] device-mapper: uevent: version 1.0.3 [ 3.830538] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. [ 4.150064] random: fast init done Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.546776] virtio_net virtio0 ens2: renamed from eth0 [ 4.597842] scsi host0: ata_piix [ 4.623543] scsi host1: ata_piix [ 4.625334] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.628314] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.346292] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.576853] random: crng init done [ 9.578495] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.974600] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.172395] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.447887] SELinux: Disabled at runtime. [ 11.507938] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.513615] systemd[1]: Detected virtualization kvm. [ 11.514496] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.007436] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.010994] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.016367] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.020353] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.023970] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.031869] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.039619] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on RPCbind Server Activation Socket. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ 12.179709] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.527145] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.806369] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.924875] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.066290] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.076489] EDAC sbridge: Ver: 1.1.2 [ 14.221188] Key type dns_resolver registered [ 14.515870] NFS: Registering the id_resolver key type [ 14.517856] Key type id_resolver registered [ 14.519388] Key type id_legacy registered [ 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 Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] 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 Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ 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 System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg107-client login: [ 45.685482] libcfs: loading out-of-tree module taints kernel. [ 45.789772] Key type ._llcrypt registered [ 45.791668] Key type .llcrypt registered [ 46.263951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 46.282597] alg: No test for adler32 (adler32-zlib) [ 47.381800] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 48.088554] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 49.783334] Key type lgssc registered [ 51.099012] hrtimer: interrupt took 2256381 ns [ 51.383432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 237.750545] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 237.988246] LustreError: 6193:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 238.000220] LustreError: 6193:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 240.182840] LustreError: 6246:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 240.186382] LustreError: 6246:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 243.729174] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 257.687958] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing check_logdir /tmp/testlogs/ [ 263.152038] Lustre: lustre-OST0000-osc-ffff924a49f67000: disconnect after 23s idle [ 265.233890] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing yml_node [ 268.843731] Lustre: DEBUG MARKER: Client: 2.17.58.39 [ 271.945555] Lustre: DEBUG MARKER: MDS: 2.17.58.39 [ 275.204379] Lustre: DEBUG MARKER: OSS: 2.17.58.39 [ 277.067257] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 14 12:35:43 EDT 2026 [ 296.521761] Lustre: DEBUG MARKER: excepting tests: 102 106 115 32newtarball [ 298.503950] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 299.782744] LustreError: 9006:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 299.786993] LustreError: 9006:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 299.791561] LustreError: 9006:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 299.796330] LustreError: 9006:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 301.556338] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 311.886258] Lustre: Unmounted lustre-client [ 419.679995] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 426.427789] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 455.007890] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 498.717927] Lustre: DEBUG MARKER: == conf-sanity test 76a: set permanent params with lctl across mounts ========================================================== 12:39:24 (1789403964) [ 535.615789] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 541.168956] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 564.277493] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 565.399270] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 567.916795] LustreError: 14351:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 567.934380] LustreError: 14351:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 572.094545] LustreError: 14428:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 572.097774] LustreError: 14428:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 572.101586] LustreError: 14428:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 572.105634] LustreError: 14428:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 574.313932] Lustre: Unmounted lustre-client [ 706.020277] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 706.270129] LustreError: 16430:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 706.278427] LustreError: 16430:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 706.292957] LustreError: 16430:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 706.302973] LustreError: 16430:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 711.826957] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 723.607067] LustreError: 16977:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 723.616482] LustreError: 16977:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [ 723.623556] LustreError: 16977:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 723.640220] LustreError: 16977:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 725.491979] Lustre: Unmounted lustre-client [ 838.764336] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 839.050826] LustreError: 18747:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 839.057103] LustreError: 18747:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 839.068401] LustreError: 18747:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 839.077416] LustreError: 18747:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 844.778703] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 853.384800] Lustre: Unmounted lustre-client [ 901.241200] Lustre: DEBUG MARKER: == conf-sanity test 76b: verify params log setup correctly ========================================================== 12:46:08 (1789404368) [ 1000.878587] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1001.187373] LustreError: 21975:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1001.207320] LustreError: 21975:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 1001.234662] LustreError: 21975:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1001.243773] LustreError: 21975:0:(namei.c:956:ll_intent_lock()) Skipped 6 previous similar messages [ 1006.452234] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1013.241796] Lustre: Unmounted lustre-client [ 1053.454718] Lustre: DEBUG MARKER: == conf-sanity test 76c: verify changelog_mask is applied with lctl set_param -P ========================================================== 12:48:40 (1789404520) [ 1154.964529] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1155.218875] LustreError: 25004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1155.232918] LustreError: 25004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 1155.254991] LustreError: 25004:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1155.261127] LustreError: 25004:0:(namei.c:956:ll_intent_lock()) Skipped 6 previous similar messages [ 1160.485391] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1170.447623] Lustre: lustre-MDT0000-mdc-ffff924a44f1f800: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1180.641794] Lustre: lustre-OST0000-osc-ffff924a44f1f800: disconnect after 24s idle [ 1180.643693] Lustre: lustre-MDT0001-mdc-ffff924a44f1f800: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1180.647588] Lustre: Skipped 1 previous similar message [ 1190.815982] Lustre: 2392:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789404643/real 1789404643] req@ffff924a47c0bb80 x1876325581240832/t0(0) o400->MGC192.168.201.107@tcp@192.168.201.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1789404659 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'kworker.0' uid:0 gid:0 projid:4294967295 [ 1190.842519] LustreError: MGC192.168.201.107@tcp: Connection to MGS (at 192.168.201.107@tcp) was lost; in progress operations using this service will fail [ 1201.131303] Lustre: Evicted from MGS (at 192.168.201.107@tcp) after server handle changed from 0x2d577de62cd1224b to 0x2d577de62cd12301 [ 1201.143703] Lustre: MGC192.168.201.107@tcp: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 1203.123135] Lustre: 24971:0:(mgc_request.c:1900:mgc_process_log()) MGC192.168.201.107@tcp: IR log lustre-cliir failed, not fatal: rc = -2 [ 1225.717861] LustreError: lustre-MDT0000-mdc-ffff924a44f1f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 1225.759720] Lustre: lustre-MDT0000-mdc-ffff924a44f1f800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 1231.683102] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1233.516857] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1240.304887] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1242.082264] Lustre: DEBUG MARKER: mdc.lustre-MDT0001-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1245.759955] Lustre: Unmounted lustre-client [ 1285.130796] Lustre: DEBUG MARKER: == conf-sanity test 76d: verify llite.*.xattr_cache can be set by 'lctl set_param -P' correctly ========================================================== 12:52:32 (1789404752) [ 1377.537808] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1383.650557] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 1395.770876] Lustre: Unmounted lustre-client [ 1456.184558] Lustre: DEBUG MARKER: == conf-sanity test 77: comma-separated MGS NIDs and failover node NIDs ========================================================== 12:55:22 (1789404922) [ 1459.725932] Lustre: DEBUG MARKER: SKIP: conf-sanity test_77 mixed loopback and real device not working [ 1461.838001] Lustre: DEBUG MARKER: == conf-sanity test 78: run resize2fs on MDT and OST filesystems ========================================================== 12:55:28 (1789404928) [ 1525.951628] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1552.400360] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1553.472549] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1553.486284] Lustre: Skipped 2 previous similar messages [ 1555.446557] Lustre: DEBUG MARKER: create test files [ 1555.538500] LustreError: 33378:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1555.549870] LustreError: 33378:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 23 previous similar messages [ 1555.573907] LustreError: 33378:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1555.580971] LustreError: 33378:0:(namei.c:956:ll_intent_lock()) Skipped 23 previous similar messages [ 1555.603629] LustreError: 33378:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 1555.760361] LustreError: 33392:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1555.788475] LustreError: 33392:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-1, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1555.799350] LustreError: 33392:0:(namei.c:1744:ll_create_it()) inode ffff924a58690908 need_sync_to_mds [0x200000401:0x2:0x0] [ 1555.809030] LustreError: 33392:0:(dcache.c:176:ll_intent_release()) intent ffff924a45fba360 released [ 1556.379793] LustreError: 33395:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1556.387287] LustreError: 33395:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 2 previous similar messages [ 1556.443928] LustreError: 33395:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-4, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1556.459437] LustreError: 33395:0:(namei.c:1721:ll_create_it()) Skipped 2 previous similar messages [ 1556.467307] LustreError: 33395:0:(namei.c:1744:ll_create_it()) inode ffff924a58531148 need_sync_to_mds [0x200000401:0x5:0x0] [ 1556.473869] LustreError: 33395:0:(namei.c:1744:ll_create_it()) Skipped 2 previous similar messages [ 1556.482874] LustreError: 33395:0:(dcache.c:176:ll_intent_release()) intent ffff924a5085ba80 released [ 1556.494666] LustreError: 33395:0:(dcache.c:176:ll_intent_release()) Skipped 2 previous similar messages [ 1557.706866] LustreError: 33400:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1557.717575] LustreError: 33400:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 4 previous similar messages [ 1557.796215] LustreError: 33400:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-9, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1557.808912] LustreError: 33400:0:(namei.c:1721:ll_create_it()) Skipped 4 previous similar messages [ 1557.816961] LustreError: 33400:0:(namei.c:1744:ll_create_it()) inode ffff924a585363c8 need_sync_to_mds [0x200000401:0xa:0x0] [ 1557.832387] LustreError: 33400:0:(namei.c:1744:ll_create_it()) Skipped 4 previous similar messages [ 1557.846933] LustreError: 33400:0:(dcache.c:176:ll_intent_release()) intent ffff924a47eb9b40 released [ 1557.861358] LustreError: 33400:0:(dcache.c:176:ll_intent_release()) Skipped 4 previous similar messages [ 1559.844546] LustreError: 33411:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1559.860516] LustreError: 33411:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 9 previous similar messages [ 1559.942306] LustreError: 33411:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-19, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1559.963834] LustreError: 33411:0:(namei.c:1721:ll_create_it()) Skipped 9 previous similar messages [ 1559.969735] LustreError: 33411:0:(namei.c:1744:ll_create_it()) inode ffff924a586a3a88 need_sync_to_mds [0x200000401:0x14:0x0] [ 1559.977602] LustreError: 33411:0:(namei.c:1744:ll_create_it()) Skipped 9 previous similar messages [ 1559.986288] LustreError: 33411:0:(dcache.c:176:ll_intent_release()) intent ffff924a50052b40 released [ 1559.993059] LustreError: 33411:0:(dcache.c:176:ll_intent_release()) Skipped 9 previous similar messages [ 1563.959790] LustreError: 33430:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1563.968383] LustreError: 33430:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 18 previous similar messages [ 1564.020270] LustreError: 33430:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-38, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1564.030835] LustreError: 33430:0:(namei.c:1721:ll_create_it()) Skipped 18 previous similar messages [ 1564.037327] LustreError: 33430:0:(namei.c:1744:ll_create_it()) inode ffff924a58536c08 need_sync_to_mds [0x200000401:0x27:0x0] [ 1564.047256] LustreError: 33430:0:(namei.c:1744:ll_create_it()) Skipped 18 previous similar messages [ 1564.056254] LustreError: 33430:0:(dcache.c:176:ll_intent_release()) intent ffff924a5085b300 released [ 1564.062951] LustreError: 33430:0:(dcache.c:176:ll_intent_release()) Skipped 18 previous similar messages [ 1572.092785] LustreError: 33461:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1572.101909] LustreError: 33461:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 28 previous similar messages [ 1572.165581] LustreError: 33461:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-67, dir=[0x200000401:0x1:0x0](ffff924a586a5348), intent=open|creat [ 1572.180099] LustreError: 33461:0:(namei.c:1721:ll_create_it()) Skipped 28 previous similar messages [ 1572.189935] LustreError: 33461:0:(namei.c:1744:ll_create_it()) inode ffff924a5876db88 need_sync_to_mds [0x200000401:0x44:0x0] [ 1572.202145] LustreError: 33461:0:(namei.c:1744:ll_create_it()) Skipped 28 previous similar messages [ 1572.222696] LustreError: 33461:0:(dcache.c:176:ll_intent_release()) intent ffff924a50052ba0 released [ 1572.229426] LustreError: 33461:0:(dcache.c:176:ll_intent_release()) Skipped 28 previous similar messages [ 1592.868191] Lustre: Unmounted lustre-client [ 1592.873191] Lustre: Skipped 2 previous similar messages [ 1613.288780] Key type lgssc unregistered [ 1613.659332] LNet: 33869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1613.674178] LNetError: 33869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1613.694127] LNet: Removed LNI 192.168.201.7@tcp [ 1614.632380] Key type .llcrypt unregistered [ 1614.636343] Key type ._llcrypt unregistered [ 1644.259719] Key type ._llcrypt registered [ 1644.269125] Key type .llcrypt registered [ 1644.893851] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1644.906605] alg: No test for adler32 (adler32-zlib) [ 1645.989624] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1646.178566] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 1647.839462] Key type lgssc registered [ 1649.072695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1687.973569] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1712.253870] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1714.085726] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1716.243611] LustreError: 35949:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1716.269610] LustreError: 35949:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1717.817444] Lustre: DEBUG MARKER: check files after expanding the MDT and OST filesystems [ 1718.069229] LustreError: 36111:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1718.074944] LustreError: 36111:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 1718.082728] LustreError: 36111:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1718.091921] LustreError: 36111:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 1718.126145] LustreError: 36111:0:(dcache.c:176:ll_intent_release()) intent 00000000c86c6050 released [ 1718.637259] LustreError: 36127:0:(dcache.c:176:ll_intent_release()) intent 000000005e2f4c2a released [ 1718.644158] LustreError: 36127:0:(dcache.c:176:ll_intent_release()) Skipped 29 previous similar messages [ 1719.082299] LustreError: 36138:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1719.087417] LustreError: 36138:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 90 previous similar messages [ 1719.094651] LustreError: 36138:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1719.106335] LustreError: 36138:0:(namei.c:956:ll_intent_lock()) Skipped 63 previous similar messages [ 1719.703214] LustreError: 36153:0:(dcache.c:176:ll_intent_release()) intent 0000000078ba403a released [ 1719.710194] LustreError: 36153:0:(dcache.c:176:ll_intent_release()) Skipped 46 previous similar messages [ 1721.089668] LustreError: 36188:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1721.120898] LustreError: 36188:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 177 previous similar messages [ 1721.132652] LustreError: 36188:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000401:0x1:0x0] suppgids 0 0: rc 1 [ 1721.152390] LustreError: 36188:0:(namei.c:956:ll_intent_lock()) Skipped 115 previous similar messages [ 1721.706304] LustreError: 36204:0:(dcache.c:176:ll_intent_release()) intent 000000005f10ed0a released [ 1721.712822] LustreError: 36204:0:(dcache.c:176:ll_intent_release()) Skipped 92 previous similar messages [ 1724.682663] Lustre: DEBUG MARKER: create more files after expanding the MDT and OST filesystems [ 1724.822800] LustreError: 36370:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1724.878815] LustreError: 36370:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-101, dir=[0x200000401:0x1:0x0](000000009a17ea4d), intent=open|creat [ 1724.893202] LustreError: 36370:0:(namei.c:1744:ll_create_it()) inode 0000000050e464cb need_sync_to_mds [0x200000bd1:0x1:0x0] [ 1725.129224] LustreError: 36370:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1725.143829] LustreError: 36370:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 177 previous similar messages [ 1725.185281] LustreError: 36380:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1725.193355] LustreError: 36380:0:(namei.c:956:ll_intent_lock()) Skipped 122 previous similar messages [ 1725.479417] LustreError: 36381:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1725.486376] LustreError: 36381:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1 previous similar message [ 1725.567656] LustreError: 36381:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-103, dir=[0x200000401:0x1:0x0](000000009a17ea4d), intent=open|creat [ 1725.588510] LustreError: 36381:0:(namei.c:1721:ll_create_it()) Skipped 1 previous similar message [ 1725.597733] LustreError: 36381:0:(namei.c:1744:ll_create_it()) inode 00000000a8f61fc7 need_sync_to_mds [0x200000bd1:0x3:0x0] [ 1725.606789] LustreError: 36381:0:(namei.c:1744:ll_create_it()) Skipped 1 previous similar message [ 1725.967290] LustreError: 36382:0:(dcache.c:176:ll_intent_release()) intent 000000003e514c71 released [ 1725.978594] LustreError: 36382:0:(dcache.c:176:ll_intent_release()) Skipped 76 previous similar messages [ 1726.668579] LustreError: 36385:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1726.684711] LustreError: 36385:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 3 previous similar messages [ 1726.792754] LustreError: 36385:0:(namei.c:1721:ll_create_it()) VFS Op:name=f78.conf-sanity-107, dir=[0x200000401:0x1:0x0](000000009a17ea4d), intent=open|creat [ 1726.824157] LustreError: 36385:0:(namei.c:1721:ll_create_it()) Skipped 3 previous similar messages [ 1726.842247] LustreError: 36385:0:(namei.c:1744:ll_create_it()) inode 000000004d94ca2f need_sync_to_mds [0x200000bd1:0x7:0x0] [ 1726.868636] LustreError: 36385:0:(namei.c:1744:ll_create_it()) Skipped 3 previous similar messages [ 1731.542577] Lustre: Unmounted lustre-client [ 1750.817825] Key type lgssc unregistered [ 1751.105730] LNet: 36758:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1751.117369] LNetError: 36758:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1751.141213] LNet: Removed LNI 192.168.201.7@tcp [ 1751.893047] Key type .llcrypt unregistered [ 1751.898648] Key type ._llcrypt unregistered [ 1780.791587] Key type ._llcrypt registered [ 1780.797558] Key type .llcrypt registered [ 1781.262200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1781.286309] alg: No test for adler32 (adler32-zlib) [ 1782.611286] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1782.915675] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 1784.623390] Key type lgssc registered [ 1786.724649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1825.648798] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1853.790932] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1855.551940] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1857.828337] LustreError: 38871:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1857.851448] LustreError: 38871:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1860.349320] Lustre: DEBUG MARKER: check files after shrinking the MDT and OST filesystems [ 1860.454598] LustreError: 39025:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1860.467703] LustreError: 39025:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 1860.480157] LustreError: 39025:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1860.487151] LustreError: 39025:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 1860.514853] LustreError: 39025:0:(dcache.c:176:ll_intent_release()) intent 0000000043fd889b released [ 1861.016038] LustreError: 39048:0:(dcache.c:176:ll_intent_release()) intent 0000000002599829 released [ 1861.030349] LustreError: 39048:0:(dcache.c:176:ll_intent_release()) Skipped 28 previous similar messages [ 1861.476491] LustreError: 39060:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1861.487172] LustreError: 39060:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 87 previous similar messages [ 1861.496441] LustreError: 39060:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1861.513122] LustreError: 39060:0:(namei.c:956:ll_intent_lock()) Skipped 60 previous similar messages [ 1862.047684] LustreError: 39075:0:(dcache.c:176:ll_intent_release()) intent 000000004c6cdf16 released [ 1862.057712] LustreError: 39075:0:(dcache.c:176:ll_intent_release()) Skipped 46 previous similar messages [ 1863.503650] LustreError: 39121:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1863.522960] LustreError: 39121:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 203 previous similar messages [ 1863.532666] LustreError: 39121:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1863.552765] LustreError: 39121:0:(namei.c:956:ll_intent_lock()) Skipped 140 previous similar messages [ 1864.075066] LustreError: 39135:0:(dcache.c:176:ll_intent_release()) intent 00000000d34f8827 released [ 1864.095427] LustreError: 39135:0:(dcache.c:176:ll_intent_release()) Skipped 108 previous similar messages [ 1867.572448] Lustre: Unmounted lustre-client [ 1886.198127] Key type lgssc unregistered [ 1886.480045] LNet: 39546:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1886.494544] LNetError: 39546:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1886.512103] LNet: Removed LNI 192.168.201.7@tcp [ 1887.230360] Key type .llcrypt unregistered [ 1887.233816] Key type ._llcrypt unregistered [ 1923.448755] Key type ._llcrypt registered [ 1923.457659] Key type .llcrypt registered [ 1924.099975] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1924.136778] alg: No test for adler32 (adler32-zlib) [ 1925.367537] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1925.626404] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 1927.337087] Key type lgssc registered [ 1929.252918] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2018.471881] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2025.509171] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2052.441698] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2104.924721] Lustre: DEBUG MARKER: == conf-sanity test 79: format MDT/OST without mgs option (should return errors) ========================================================== 13:06:11 (1789405571) [ 2249.473605] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2256.336954] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2282.636266] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2329.693566] Lustre: DEBUG MARKER: == conf-sanity test 80: mgc import reconnect race ======== 13:09:56 (1789405796) [ 2365.697891] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2372.610486] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2397.064934] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2458.426485] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2524.125800] Lustre: DEBUG MARKER: == conf-sanity test 81: sparse OST indexing ============== 13:13:10 (1789405990) [ 2526.251139] Lustre: DEBUG MARKER: SKIP: conf-sanity test_81 needs >= 3 OSTs [ 2528.030525] Lustre: DEBUG MARKER: == conf-sanity test 82a: specify OSTs for file (succeed) or directory (succeed) ========================================================== 13:13:15 (1789405995) [ 2529.827617] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82a needs >= 3 OSTs [ 2531.577184] Lustre: DEBUG MARKER: == conf-sanity test 82b: specify OSTs for file with --pool and --ost-list options ========================================================== 13:13:18 (1789405998) [ 2533.355566] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82b needs >= 4 OSTs [ 2535.343827] Lustre: DEBUG MARKER: == conf-sanity test 82c: specify sparse OSTs for setstripe (should not crash) ========================================================== 13:13:22 (1789406002) [ 2536.928500] Lustre: DEBUG MARKER: SKIP: conf-sanity test_82c needs >= 4 OSTs [ 2538.826585] Lustre: DEBUG MARKER: == conf-sanity test 83: ENOSPACE on OST doesn't cause message VFS: Busy inodes after unmount ... ========================================================== 13:13:25 (1789406005) [ 2540.136516] Lustre: DEBUG MARKER: mount the OST /dev/mapper/ost1_flakey as a ldiskfs filesystem [ 2549.642823] Lustre: DEBUG MARKER: run llverfs in partial mode on the OST ldiskfs /mnt/lustre-ost1 [ 2561.643728] Lustre: DEBUG MARKER: unmount the OST /dev/mapper/ost1_flakey [ 2708.560204] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2715.154597] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2739.555752] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2781.829468] Lustre: DEBUG MARKER: == conf-sanity test 84: check recovery_hard_time ========= 13:17:28 (1789406248) [ 2824.969686] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2831.997709] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2860.030137] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2897.008378] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2910.314889] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2910.986182] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2922.794789] LustreError: 61213:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2922.829757] LustreError: 61213:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2923.008568] LustreError: 61220:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 2923.094413] LustreError: 61223:0:(dcache.c:176:ll_intent_release()) intent 0000000022522e87 released [ 2923.302837] LustreError: 61230:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2923.310794] LustreError: 61230:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [ 2923.423923] LustreError: 61233:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2923.431120] LustreError: 61233:0:(namei.c:956:ll_intent_lock()) Skipped 14 previous similar messages [ 2932.494092] Lustre: DEBUG MARKER: local REPLAY BARRIER on lustre-MDT0000 [ 2936.532640] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2936.576371] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 9 previous similar messages [ 2936.611544] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2936.628297] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 2936.648800] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2936.692405] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-0, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2936.702659] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 0000000030377efb need_sync_to_mds [0x200000bd1:0x2:0x0] [ 2936.708931] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) intent 0000000047aec3a2 released [ 2936.712557] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) Skipped 1 previous similar message [ 2937.158872] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2937.174161] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 13 previous similar messages [ 2937.264736] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-14, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2937.281069] LustreError: 61422:0:(namei.c:1721:ll_create_it()) Skipped 13 previous similar messages [ 2937.294138] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 0000000064a4f8e2 need_sync_to_mds [0x200000bd1:0x10:0x0] [ 2937.311204] LustreError: 61422:0:(namei.c:1744:ll_create_it()) Skipped 13 previous similar messages [ 2937.725888] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) intent 00000000f94f38ee released [ 2937.733626] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) Skipped 24 previous similar messages [ 2938.168881] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2938.175079] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 24 previous similar messages [ 2938.279492] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-41, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2938.292627] LustreError: 61422:0:(namei.c:1721:ll_create_it()) Skipped 26 previous similar messages [ 2938.299988] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 00000000410fe8b6 need_sync_to_mds [0x200000bd1:0x2b:0x0] [ 2938.306447] LustreError: 61422:0:(namei.c:1744:ll_create_it()) Skipped 26 previous similar messages [ 2938.533467] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2938.542354] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 203 previous similar messages [ 2938.625567] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000007:0x1:0x0] suppgids 0 -1: rc 0 [ 2938.643039] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) Skipped 157 previous similar messages [ 2939.738678] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) intent 000000005bdbae2e released [ 2939.743628] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) Skipped 74 previous similar messages [ 2940.168733] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2940.175446] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 84 previous similar messages [ 2940.300673] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-128, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2940.308713] LustreError: 61422:0:(namei.c:1721:ll_create_it()) Skipped 86 previous similar messages [ 2940.314938] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 000000005688254c need_sync_to_mds [0x200000bd1:0x82:0x0] [ 2940.322188] LustreError: 61422:0:(namei.c:1744:ll_create_it()) Skipped 86 previous similar messages [ 2942.539617] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2942.548349] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 734 previous similar messages [ 2942.638896] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2942.647914] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) Skipped 555 previous similar messages [ 2943.749413] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) intent 000000004f4a8281 released [ 2943.767294] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) Skipped 192 previous similar messages [ 2944.169844] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2944.179558] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 182 previous similar messages [ 2944.308066] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-311, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2944.315663] LustreError: 61422:0:(namei.c:1721:ll_create_it()) Skipped 182 previous similar messages [ 2944.320933] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 0000000031ffbf85 need_sync_to_mds [0x200000bd1:0x139:0x0] [ 2944.328532] LustreError: 61422:0:(namei.c:1744:ll_create_it()) Skipped 182 previous similar messages [ 2950.547666] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2950.557752] LustreError: 61422:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1363 previous similar messages [ 2950.643978] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2950.652405] LustreError: 61422:0:(namei.c:956:ll_intent_lock()) Skipped 1019 previous similar messages [ 2951.766389] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) intent 00000000f4bdf991 released [ 2951.783230] LustreError: 61422:0:(dcache.c:176:ll_intent_release()) Skipped 330 previous similar messages [ 2952.176488] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2952.188122] LustreError: 61422:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 333 previous similar messages [ 2952.321317] LustreError: 61422:0:(namei.c:1721:ll_create_it()) VFS Op:name=f84.conf-sanity-645, dir=[0x200000007:0x1:0x0](00000000fc26a612), intent=open|creat [ 2952.332872] LustreError: 61422:0:(namei.c:1721:ll_create_it()) Skipped 333 previous similar messages [ 2952.344451] LustreError: 61422:0:(namei.c:1744:ll_create_it()) inode 00000000eb700312 need_sync_to_mds [0x200000bd1:0x287:0x0] [ 2952.358311] LustreError: 61422:0:(namei.c:1744:ll_create_it()) Skipped 333 previous similar messages [ 2967.529368] Lustre: lustre-MDT0000-mdc-ffff924a48e08000: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2982.883787] Lustre: 40161:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789406435/real 1789406435] req@00000000d2c7351b x1876327551076096/t0(0) o400->MGC192.168.201.107@tcp@192.168.201.107@tcp:26/25 lens 224/224 e 0 to 1 dl 1789406451 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2982.916335] LustreError: MGC192.168.201.107@tcp: Connection to MGS (at 192.168.201.107@tcp) was lost; in progress operations using this service will fail [ 2993.135052] Lustre: Evicted from MGS (at 192.168.201.107@tcp) after server handle changed from 0x83b28a8981d60a24 to 0x83b28a8981d6cc02 [ 2993.164195] Lustre: MGC192.168.201.107@tcp: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 2996.485765] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000047ee5ce2 x1876327550432384/t8589934597(8589934597) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406480 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2997.167183] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000c2c58f87 x1876327550433024/t8589934599(8589934599) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406481 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2998.495149] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000a553dd6e x1876327550434304/t8589934603(8589934603) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406482 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 2998.553853] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) Skipped 1 previous similar message [ 3000.188200] LustreError: 61877:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3000.200393] LustreError: 61877:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1696 previous similar messages [ 3000.504492] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000009bf54d40 x1876327550436992/t8589934609(8589934609) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406484 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3000.529753] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) Skipped 2 previous similar messages [ 3005.071103] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@0000000091713f17 x1876327550441472/t8589934623(8589934623) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406489 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3005.087756] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) Skipped 6 previous similar messages [ 3013.446758] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@00000000f38b07df x1876327550449792/t8589934649(8589934649) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406497 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3013.470942] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) Skipped 12 previous similar messages [ 3029.540448] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@000000009f241bdd x1876327550465792/t8589934699(8589934699) o101->lustre-MDT0000-mdc-ffff924a48e08000@192.168.201.107@tcp:12/10 lens 592/608 e 0 to 0 dl 1789406513 ref 2 fl Interpret:RQU/604/0 rc 301/301 job:'' uid:0 gid:0 projid:0 [ 3029.590483] LustreError: 40158:0:(client.c:3449:ptlrpc_replay_interpret()) Skipped 24 previous similar messages [ 3053.348723] LustreError: lustre-MDT0000-mdc-ffff924a48e08000: operation mds_close to node 192.168.201.107@tcp failed: rc = -107 [ 3053.449396] Lustre: lustre-MDT0000-mdc-ffff924a44f2a800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 3054.576270] LustreError: lustre-MDT0000-mdc-ffff924a48e08000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 3054.836298] LustreError: 61877:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc -5 [ 3054.847805] LustreError: 61877:0:(namei.c:956:ll_intent_lock()) Skipped 1265 previous similar messages [ 3054.856776] LustreError: 61877:0:(file.c:6175:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -5 [ 3055.451370] Lustre: lustre-MDT0000-mdc-ffff924a48e08000: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 3060.773360] LustreError: 61965:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3063.593407] Lustre: Unmounted lustre-client [ 3066.041132] Lustre: Unmounted lustre-client [ 3110.581669] Lustre: DEBUG MARKER: == conf-sanity test 85: osd_ost init: fail ea_fid_set ==== 13:22:57 (1789406577) [ 3179.702818] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3191.428597] Lustre: DEBUG MARKER: == conf-sanity test 86: Replacing mkfs.lustre -G option == 13:24:18 (1789406658) [ 3228.508727] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3369.592723] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3375.665180] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3402.813861] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3455.104524] Lustre: DEBUG MARKER: == conf-sanity test 87: check if MDT inode can hold EAs with N stripes properly ========================================================== 13:28:41 (1789406921) [ 3461.833592] Key type lgssc unregistered [ 3462.281321] LNet: 69329:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3462.296445] LNetError: 69329:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3462.345592] LNet: Removed LNI 192.168.201.7@tcp [ 3463.813421] Key type .llcrypt unregistered [ 3463.814530] Key type ._llcrypt unregistered [ 3499.101951] Key type ._llcrypt registered [ 3499.111454] Key type .llcrypt registered [ 3499.591608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3499.644108] alg: No test for adler32 (adler32-zlib) [ 3500.944474] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 3501.351080] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 3503.146975] Key type lgssc registered [ 3504.803466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3622.298079] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 3622.528359] LustreError: 72547:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3622.546929] LustreError: 72547:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 3622.567705] LustreError: 72547:0:(dcache.c:176:ll_intent_release()) intent 0000000078207eaf released [ 3622.581176] LustreError: 72547:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 3622.615686] LustreError: 72547:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](0000000037245445), intent=open|creat [ 3622.622802] LustreError: 72547:0:(namei.c:1744:ll_create_it()) inode 000000003859532d need_sync_to_mds [0x200000401:0x1:0x0] [ 3623.046441] LustreError: 72562:0:(namei.c:956:ll_intent_lock()) intent lock 128 on i1 [0x200000401:0x2:0x0] suppgids 0 -1: rc 0 [ 3623.058286] LustreError: 72562:0:(namei.c:956:ll_intent_lock()) Skipped 18 previous similar messages [ 3623.076621] LustreError: 72562:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3623.092243] LustreError: 72562:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 23 previous similar messages [ 3623.253702] LustreError: 72563:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 3623.264608] LustreError: 72563:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1 previous similar message [ 3623.289958] LustreError: 72563:0:(dcache.c:176:ll_intent_release()) intent 00000000ea8e2e6c released [ 3623.300266] LustreError: 72563:0:(dcache.c:176:ll_intent_release()) Skipped 7 previous similar messages [ 3623.575957] Lustre: Unmounted lustre-client [ 3657.850253] Lustre: DEBUG MARKER: == conf-sanity test 88a: tunefs.lustre persists a --mountfsoptions-only change ========================================================== 13:32:03 (1789407123) [ 3685.060976] Lustre: DEBUG MARKER: == conf-sanity test 88b: check the default mount options can be overridden ========================================================== 13:32:31 (1789407151) [ 3721.550887] Lustre: DEBUG MARKER: == conf-sanity test 89: check tunefs --param and --erase-param{s} options ========================================================== 13:33:08 (1789407188) [ 3859.663945] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3869.804643] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL failover.node=192.0.2.254@tcp0,mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3897.031642] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) failover.node=192.0.2.254@tcp0,osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3951.679361] Lustre: DEBUG MARKER: == conf-sanity test 90a: check max_mod_rpcs_in_flight is enforced ========================================================== 13:36:58 (1789407418) [ 3988.774067] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3996.406601] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4026.562801] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4033.103844] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4035.140601] LustreError: 81768:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4035.149431] LustreError: 81768:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 16 previous similar messages [ 4035.167460] LustreError: 81768:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4035.174335] LustreError: 81768:0:(namei.c:956:ll_intent_lock()) Skipped 15 previous similar messages [ 4035.378980] LustreError: 81791:0:(dcache.c:176:ll_intent_release()) intent 00000000edf2e416 released [ 4035.387510] LustreError: 81791:0:(dcache.c:176:ll_intent_release()) Skipped 2 previous similar messages [ 4035.408970] LustreError: 81791:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 4035.524616] LustreError: 81800:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4035.703224] LustreError: 81803:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-1, dir=[0x200000bd1:0x1:0x0](00000000bcbd8b58), intent=open|creat [ 4035.706386] LustreError: 81803:0:(namei.c:1721:ll_create_it()) Skipped 1 previous similar message [ 4035.709459] LustreError: 81803:0:(namei.c:1744:ll_create_it()) inode 00000000392dc6e1 need_sync_to_mds [0x200000bd1:0x2:0x0] [ 4035.713035] LustreError: 81803:0:(namei.c:1744:ll_create_it()) Skipped 1 previous similar message [ 4037.595981] LustreError: 81821:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4037.606275] LustreError: 81820:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4037.621877] LustreError: 81821:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 60 previous similar messages [ 4037.655049] LustreError: 81820:0:(namei.c:956:ll_intent_lock()) Skipped 41 previous similar messages [ 4037.668719] LustreError: 81824:0:(dcache.c:176:ll_intent_release()) intent 00000000079fd9b6 released [ 4037.678617] LustreError: 81824:0:(dcache.c:176:ll_intent_release()) Skipped 12 previous similar messages [ 4053.983213] Lustre: 81822:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407506/real 1789407506] req@000000002e40a811 x1876329201953152/t0(0) o36->lustre-MDT0000-mdc-ffff924a4553c000@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407522 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4053.983458] Lustre: lustre-MDT0000-mdc-ffff924a4553c000: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4054.020121] Lustre: 81822:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4054.113486] Lustre: lustre-MDT0000-mdc-ffff924a4553c000: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4056.276634] LustreError: 81860:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4056.299770] LustreError: 81861:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4056.306925] LustreError: 81860:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 71 previous similar messages [ 4056.349426] LustreError: 81861:0:(namei.c:956:ll_intent_lock()) Skipped 27 previous similar messages [ 4056.374440] LustreError: 81860:0:(dcache.c:176:ll_intent_release()) intent 000000001b883123 released [ 4056.389518] LustreError: 81860:0:(dcache.c:176:ll_intent_release()) Skipped 43 previous similar messages [ 4071.903731] Lustre: 81865:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407524/real 1789407524] req@000000008256ad72 x1876329201964544/t0(0) o36->lustre-MDT0000-mdc-ffff924a4553c000@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407540 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4071.905081] Lustre: lustre-MDT0000-mdc-ffff924a4553c000: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4071.933753] Lustre: 81865:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 4072.031952] Lustre: lustre-MDT0000-mdc-ffff924a4553c000: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4073.047611] LustreError: 81902:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4073.054521] LustreError: 81902:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 83 previous similar messages [ 4073.065151] LustreError: 81902:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4073.072904] LustreError: 81902:0:(namei.c:956:ll_intent_lock()) Skipped 27 previous similar messages [ 4086.768360] LustreError: 69934:0:(dcache.c:176:ll_intent_release()) intent 000000005ed945ae released [ 4086.779893] LustreError: 69934:0:(dcache.c:176:ll_intent_release()) Skipped 50 previous similar messages [ 4087.059253] Lustre: Unmounted lustre-client [ 4120.265079] Key type lgssc unregistered [ 4120.698305] LNet: 82316:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4120.703255] LNetError: 82316:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4120.742853] LNet: Removed LNI 192.168.201.7@tcp [ 4122.215481] Key type .llcrypt unregistered [ 4122.222374] Key type ._llcrypt unregistered [ 4143.467575] Key type ._llcrypt registered [ 4143.471936] Key type .llcrypt registered [ 4145.711181] Lustre: DEBUG MARKER: == conf-sanity test 90b: check max_mod_rpcs_in_flight is enforced after update ========================================================== 13:40:11 (1789407611) [ 4148.942239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4149.126095] alg: No test for adler32 (adler32-zlib) [ 4150.348128] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 4150.781667] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 4152.543890] Key type lgssc registered [ 4154.243118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4214.397099] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4221.344318] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4243.064297] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4255.284193] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4257.599884] LustreError: 85455:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4257.615634] LustreError: 85455:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4257.802155] LustreError: 85477:0:(dcache.c:176:ll_intent_release()) intent 00000000f3e758ab released [ 4257.826253] LustreError: 85477:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 4257.920655] LustreError: 85485:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4258.113145] LustreError: 85490:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4258.123315] LustreError: 85490:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 18 previous similar messages [ 4258.140380] LustreError: 85490:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x2000013a1:0x1:0x0] suppgids 0 -1: rc 0 [ 4258.145385] LustreError: 85490:0:(namei.c:956:ll_intent_lock()) Skipped 14 previous similar messages [ 4258.150361] LustreError: 85490:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-1, dir=[0x2000013a1:0x1:0x0](000000006de0a866), intent=open|creat [ 4258.155805] LustreError: 85490:0:(namei.c:1744:ll_create_it()) inode 000000003e889c3a need_sync_to_mds [0x2000013a1:0x2:0x0] [ 4262.235874] LustreError: 85511:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4262.251183] LustreError: 85511:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 8 previous similar messages [ 4262.266268] LustreError: 85511:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4262.276118] LustreError: 85511:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 4262.314668] LustreError: 85511:0:(dcache.c:176:ll_intent_release()) intent 00000000ed1819ff released [ 4262.324650] LustreError: 85511:0:(dcache.c:176:ll_intent_release()) Skipped 5 previous similar messages [ 4264.299490] LustreError: 85523:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4264.321768] LustreError: 85523:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 7 previous similar messages [ 4264.340596] LustreError: 85523:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4264.364618] LustreError: 85523:0:(namei.c:956:ll_intent_lock()) Skipped 6 previous similar messages [ 4266.626163] LustreError: 85533:0:(dcache.c:176:ll_intent_release()) intent 00000000d34f8827 released [ 4266.639543] LustreError: 85533:0:(dcache.c:176:ll_intent_release()) Skipped 1 previous similar message [ 4279.777758] Lustre: 85523:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407732/real 1789407732] req@00000000ed553ce1 x1876329883510400/t0(0) o36->lustre-MDT0000-mdc-ffff924a50891800@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407748 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4279.830918] Lustre: lustre-MDT0000-mdc-ffff924a50891800: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4279.917261] Lustre: lustre-MDT0000-mdc-ffff924a50891800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4280.048181] LustreError: 85546:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4280.069503] LustreError: 85546:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 27 previous similar messages [ 4280.078287] LustreError: 85546:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4280.095222] LustreError: 85546:0:(namei.c:956:ll_intent_lock()) Skipped 9 previous similar messages [ 4280.120401] LustreError: 85546:0:(dcache.c:176:ll_intent_release()) intent 00000000c67722d5 released [ 4280.128434] LustreError: 85546:0:(dcache.c:176:ll_intent_release()) Skipped 17 previous similar messages [ 4280.155491] LustreError: 85546:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 4280.413331] LustreError: 85549:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4280.427490] LustreError: 85549:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 3 previous similar messages [ 4280.493937] LustreError: 85549:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [ 4280.633208] LustreError: 85552:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-1, dir=[0x2400013a0:0x1:0x0](0000000078a0223c), intent=open|creat [ 4280.639305] LustreError: 85552:0:(namei.c:1721:ll_create_it()) Skipped 1 previous similar message [ 4280.644429] LustreError: 85552:0:(namei.c:1744:ll_create_it()) inode 000000008599595e need_sync_to_mds [0x2400013a0:0x2:0x0] [ 4280.648976] LustreError: 85552:0:(namei.c:1744:ll_create_it()) Skipped 1 previous similar message [ 4284.664758] LustreError: 85585:0:(dcache.c:176:ll_intent_release()) intent 000000004e179446 released [ 4284.677698] LustreError: 85585:0:(dcache.c:176:ll_intent_release()) Skipped 30 previous similar messages [ 4296.672164] LustreError: 85535:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4296.688682] LustreError: 85535:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 87 previous similar messages [ 4296.721269] LustreError: 83273:0:(dcache.c:176:ll_intent_release()) intent 000000003f15fb0c released [ 4296.736061] LustreError: 83273:0:(dcache.c:176:ll_intent_release()) Skipped 1 previous similar message [ 4298.719249] Lustre: 85568:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407751/real 1789407751] req@0000000012e2e577 x1876329883522688/t0(0) o36->lustre-MDT0001-mdc-ffff924a50891800@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407767 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4298.747857] Lustre: lustre-MDT0001-mdc-ffff924a50891800: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4298.815826] Lustre: lustre-MDT0001-mdc-ffff924a50891800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4300.447366] LustreError: 85600:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4300.470256] LustreError: 85600:0:(namei.c:956:ll_intent_lock()) Skipped 51 previous similar messages [ 4315.615200] Lustre: 85602:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407768/real 1789407768] req@00000000f0b90e1d x1876329883529600/t0(0) o36->lustre-MDT0001-mdc-ffff924a50891800@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407784 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4315.615328] Lustre: lustre-MDT0001-mdc-ffff924a50891800: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4315.641480] Lustre: 85602:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 4315.708250] Lustre: lustre-MDT0001-mdc-ffff924a50891800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4315.808265] LustreError: 85635:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4315.815177] LustreError: 85635:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 60 previous similar messages [ 4315.827354] LustreError: 85635:0:(dcache.c:176:ll_intent_release()) intent 000000006f51ac79 released [ 4315.833630] LustreError: 85635:0:(dcache.c:176:ll_intent_release()) Skipped 34 previous similar messages [ 4315.844779] LustreError: 85635:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 4315.880546] LustreError: 85638:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4315.886660] LustreError: 85638:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 6 previous similar messages [ 4317.674242] LustreError: 85659:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4317.683836] LustreError: 85659:0:(namei.c:956:ll_intent_lock()) Skipped 27 previous similar messages [ 4319.321206] Lustre: Unmounted lustre-client [ 4321.025531] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4321.196902] LustreError: 85725:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4321.202453] LustreError: 85725:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1 previous similar message [ 4321.338738] LustreError: 85728:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-1, dir=[0x2000013a1:0x4:0x0](00000000dcae3619), intent=open|creat [ 4321.345973] LustreError: 85728:0:(namei.c:1721:ll_create_it()) Skipped 5 previous similar messages [ 4321.351520] LustreError: 85728:0:(namei.c:1744:ll_create_it()) inode 000000000a34d6f5 need_sync_to_mds [0x2000013a3:0x1:0x0] [ 4321.358629] LustreError: 85728:0:(namei.c:1744:ll_create_it()) Skipped 5 previous similar messages [ 4338.655233] Lustre: 85763:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407791/real 1789407791] req@000000008457f19e x1876329883566080/t0(0) o36->lustre-MDT0000-mdc-ffff924a446b1000@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407807 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4338.656183] Lustre: lustre-MDT0000-mdc-ffff924a446b1000: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4338.688683] Lustre: 85763:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 4338.744862] Lustre: lustre-MDT0000-mdc-ffff924a446b1000: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4354.015471] LustreError: 85791:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4354.031643] LustreError: 85791:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 400 previous similar messages [ 4354.050717] LustreError: 80946:0:(dcache.c:176:ll_intent_release()) intent 0000000059a52408 released [ 4354.057685] LustreError: 80946:0:(dcache.c:176:ll_intent_release()) Skipped 177 previous similar messages [ 4356.575206] Lustre: 85826:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789407809/real 1789407809] req@00000000540e207c x1876329883591296/t0(0) o36->lustre-MDT0000-mdc-ffff924a446b1000@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789407825 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4356.575318] Lustre: lustre-MDT0000-mdc-ffff924a446b1000: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4356.591562] Lustre: 85826:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 12 previous similar messages [ 4356.648853] Lustre: lustre-MDT0000-mdc-ffff924a446b1000: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4358.231179] LustreError: 82953:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4358.241859] LustreError: 82953:0:(namei.c:956:ll_intent_lock()) Skipped 195 previous similar messages [ 4358.311550] LustreError: 85868:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 4374.776637] Lustre: Unmounted lustre-client [ 4408.113603] Key type lgssc unregistered [ 4408.511070] LNet: 86291:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4408.528483] LNetError: 86291:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4409.576700] LNet: Removed LNI 192.168.201.7@tcp [ 4411.297964] Key type .llcrypt unregistered [ 4411.300137] Key type ._llcrypt unregistered [ 4433.251831] Key type ._llcrypt registered [ 4433.256166] Key type .llcrypt registered [ 4435.484106] Lustre: DEBUG MARKER: == conf-sanity test 90c: check max_mod_rpcs_in_flight update limits ========================================================== 13:45:01 (1789407901) [ 4438.079265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4438.097220] alg: No test for adler32 (adler32-zlib) [ 4439.078230] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 4439.273757] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 4440.951256] Key type lgssc registered [ 4442.246617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4504.551072] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4511.259711] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4535.075487] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4542.036476] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4544.370323] LustreError: 89427:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4544.404471] LustreError: 89427:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4547.702706] LustreError: 89496:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4547.711281] LustreError: 89496:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 4547.718531] LustreError: 89496:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4547.730045] LustreError: 89496:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 4549.976290] LustreError: 89505:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4549.982413] LustreError: 89505:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4550.107199] Lustre: Unmounted lustre-client [ 4551.552253] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4551.874876] LustreError: 89561:0:(genops.c:2241:obd_set_max_mod_rpcs_in_flight()) lustre-MDT0000-mdc-ffff924a4553e800: can't set max_mod_rpcs_in_flight=9 higher than mdt.*.max_mod_rpcs_in_flight=8 returned by the MDT server at connection. [ 4554.207779] LustreError: 89587:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4554.215746] LustreError: 89587:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 4554.253499] LustreError: 89587:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4554.260482] LustreError: 89587:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 4557.019425] Lustre: Unmounted lustre-client [ 4557.644623] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4561.212634] LustreError: 89693:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4561.223428] LustreError: 89693:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 4561.244049] LustreError: 89693:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4561.256264] LustreError: 89693:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 4564.337214] Lustre: Unmounted lustre-client [ 4595.132389] Key type lgssc unregistered [ 4595.447180] LNet: 90105:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4595.459304] LNetError: 90105:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4595.488938] LNet: Removed LNI 192.168.201.7@tcp [ 4596.331207] Key type .llcrypt unregistered [ 4596.332500] Key type ._llcrypt unregistered [ 4615.404918] Key type ._llcrypt registered [ 4615.406398] Key type .llcrypt registered [ 4617.738303] Lustre: DEBUG MARKER: == conf-sanity test 90d: check one close RPC is allowed above max_mod_rpcs_in_flight ========================================================== 13:48:03 (1789408083) [ 4620.698116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4620.907325] alg: No test for adler32 (adler32-zlib) [ 4622.131870] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 4622.422740] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 4624.170769] Key type lgssc registered [ 4625.727154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4681.745543] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4688.666615] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4716.375318] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4718.667893] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4721.090228] LustreError: 93242:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4721.129053] LustreError: 93242:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4721.318386] LustreError: 93265:0:(dcache.c:176:ll_intent_release()) intent 000000000fa0fc2d released [ 4721.340231] LustreError: 93265:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 4721.483982] LustreError: 93273:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4721.606731] LustreError: 93273:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4721.625084] LustreError: 93273:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [ 4721.833766] LustreError: 93278:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4721.847947] LustreError: 93278:0:(namei.c:956:ll_intent_lock()) Skipped 12 previous similar messages [ 4721.911860] LustreError: 93278:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-1, dir=[0x200002341:0x1:0x0](0000000060067439), intent=open|creat [ 4721.922426] LustreError: 93278:0:(namei.c:1744:ll_create_it()) inode 00000000f1507ce6 need_sync_to_mds [0x200002341:0x2:0x0] [ 4721.932884] LustreError: 93278:0:(dcache.c:176:ll_intent_release()) intent 00000000add0b532 released [ 4721.939735] LustreError: 93278:0:(dcache.c:176:ll_intent_release()) Skipped 3 previous similar messages [ 4722.034371] LustreError: 93279:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4722.044615] LustreError: 93279:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 2 previous similar messages [ 4722.415292] LustreError: 93282:0:(namei.c:1721:ll_create_it()) VFS Op:name=file-5, dir=[0x200002341:0x1:0x0](0000000060067439), intent=open|creat [ 4722.420428] LustreError: 93282:0:(namei.c:1721:ll_create_it()) Skipped 3 previous similar messages [ 4722.434345] LustreError: 93282:0:(namei.c:1744:ll_create_it()) inode 00000000062f7eb2 need_sync_to_mds [0x200002341:0x6:0x0] [ 4722.440911] LustreError: 93282:0:(namei.c:1744:ll_create_it()) Skipped 3 previous similar messages [ 4722.608859] LustreError: 93284:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4722.642245] LustreError: 93284:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 34 previous similar messages [ 4722.836079] LustreError: 93286:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200002341:0x1:0x0] suppgids 0 -1: rc 0 [ 4722.840018] LustreError: 93286:0:(namei.c:956:ll_intent_lock()) Skipped 22 previous similar messages [ 4723.519374] LustreError: 93298:0:(dcache.c:176:ll_intent_release()) intent 00000000bfc5fc28 released [ 4723.523485] LustreError: 93298:0:(dcache.c:176:ll_intent_release()) Skipped 8 previous similar messages [ 4724.641187] LustreError: 93286:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4724.652611] LustreError: 93286:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 65 previous similar messages [ 4740.063213] Lustre: 93300:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789408192/real 1789408192] req@000000005c292201 x1876330377396352/t0(0) o36->lustre-MDT0000-mdc-ffff924a48c60800@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789408208 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4740.063214] Lustre: 93298:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789408192/real 1789408192] req@0000000010fedc82 x1876330377395712/t0(0) o36->lustre-MDT0000-mdc-ffff924a48c60800@192.168.201.107@tcp:12/10 lens 488/512 e 0 to 1 dl 1789408208 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 4740.063745] Lustre: lustre-MDT0000-mdc-ffff924a48c60800: Connection to lustre-MDT0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 4740.130899] Lustre: 93298:0:(client.c:2504:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 4740.277471] Lustre: lustre-MDT0000-mdc-ffff924a48c60800: Connection restored to 192.168.201.107@tcp (at 192.168.201.107@tcp) [ 4740.322329] LustreError: 93302:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4740.377747] LustreError: 93298:0:(namei.c:956:ll_intent_lock()) intent lock 1024 on i1 [0x200002341:0x3:0x0] suppgids 0 0: rc 0 [ 4740.406657] LustreError: 93298:0:(namei.c:956:ll_intent_lock()) Skipped 33 previous similar messages [ 4740.526558] LustreError: 93327:0:(dcache.c:176:ll_intent_release()) intent 00000000ba094e4e released [ 4740.531382] LustreError: 93327:0:(dcache.c:176:ll_intent_release()) Skipped 30 previous similar messages [ 4740.546916] LustreError: 93327:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 4744.090923] Lustre: Unmounted lustre-client [ 4775.625385] Key type lgssc unregistered [ 4776.053036] LNet: 93749:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4776.062793] LNetError: 93749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4776.084705] LNet: Removed LNI 192.168.201.7@tcp [ 4777.131114] Key type .llcrypt unregistered [ 4777.134986] Key type ._llcrypt unregistered [ 4798.709927] Key type ._llcrypt registered [ 4798.716890] Key type .llcrypt registered [ 4801.247154] Lustre: DEBUG MARKER: == conf-sanity test 91: evict-by-nid support ============= 13:51:06 (1789408266) [ 4804.541528] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 4804.643204] alg: No test for adler32 (adler32-zlib) [ 4806.387810] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 4807.187654] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 4809.263238] Key type lgssc registered [ 4811.946254] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4866.510791] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4873.203281] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4899.943161] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4903.451963] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4904.099594] LustreError: 96870:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4904.118726] LustreError: 96870:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4904.141588] LustreError: 96870:0:(dcache.c:176:ll_intent_release()) intent 00000000fc2b89d2 released [ 4904.196690] LustreError: 96870:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4904.281615] LustreError: 96870:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000440b6885), intent=open|creat [ 4904.304024] LustreError: 96870:0:(namei.c:1744:ll_create_it()) inode 0000000060067439 need_sync_to_mds [0x200002b11:0x1:0x0] [ 4904.625100] LustreError: 96876:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4904.657319] LustreError: 96876:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 7 previous similar messages [ 4904.698903] LustreError: 96876:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4904.711964] LustreError: 96876:0:(namei.c:956:ll_intent_lock()) Skipped 6 previous similar messages [ 4905.840326] LustreError: 96921:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4905.855936] LustreError: 96921:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 4 previous similar messages [ 4905.887854] LustreError: 96921:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4905.896196] LustreError: 96921:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 4911.738886] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 4922.116282] Lustre: Unmounted lustre-client [ 4990.781490] Key type lgssc unregistered [ 4991.428354] LNet: 97445:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4991.432903] LNetError: 97445:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4992.488264] LNet: Removed LNI 192.168.201.7@tcp [ 4993.714654] Key type .llcrypt unregistered [ 4993.723498] Key type ._llcrypt unregistered [ 5014.892209] Key type ._llcrypt registered [ 5014.893822] Key type .llcrypt registered [ 5016.639849] Lustre: DEBUG MARKER: == conf-sanity test 92: ldev returns MGS NID correctly in command substitution ========================================================== 13:54:43 (1789408483) [ 5030.332546] Lustre: DEBUG MARKER: == conf-sanity test 93: register mulitple MDT at the same time ========================================================== 13:54:56 (1789408496) [ 5032.893928] Lustre: DEBUG MARKER: SKIP: conf-sanity test_93 needs >= 3 MDTs [ 5035.724882] Lustre: DEBUG MARKER: == conf-sanity test 94: ldev outputs correct labels for file system name query ========================================================== 13:55:01 (1789408501) [ 5046.578263] Lustre: DEBUG MARKER: == conf-sanity test 95: ldev should only allow one label filter ========================================================== 13:55:13 (1789408513) [ 5057.011346] Lustre: DEBUG MARKER: == conf-sanity test 96: ldev returns hostname and backend fs correctly in command sub ========================================================== 13:55:23 (1789408523) [ 5067.220216] Lustre: DEBUG MARKER: == conf-sanity test 97: ldev returns correct ouput when querying based on role ========================================================== 13:55:33 (1789408533) [ 5077.402474] Lustre: DEBUG MARKER: == conf-sanity test 98: Buffer-overflow check while parsing mount_opts ========================================================== 13:55:44 (1789408544) [ 5080.269043] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5080.308402] alg: No test for adler32 (adler32-zlib) [ 5081.715201] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5082.189113] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 5083.903320] Key type lgssc registered [ 5085.787233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5145.420846] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5152.821213] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5181.401840] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5188.663604] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5191.154864] LustreError: 104798:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5191.169604] LustreError: 104798:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5191.422665] LustreError: 104823:0:(dcache.c:176:ll_intent_release()) intent 000000002aa033cc released [ 5191.432646] LustreError: 104823:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5191.496785] LustreError: 104823:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000ebec3e49), intent=open|creat [ 5191.503968] LustreError: 104823:0:(namei.c:1744:ll_create_it()) inode 0000000060de3dfd need_sync_to_mds [0x2000032e1:0x1:0x0] [ 5191.711431] LustreError: 104835:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5191.724900] LustreError: 104835:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 10 previous similar messages [ 5191.765455] LustreError: 104835:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5191.787770] LustreError: 104835:0:(namei.c:956:ll_intent_lock()) Skipped 9 previous similar messages [ 5194.582697] LustreError: 104863:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5194.606938] LustreError: 104863:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 5194.622548] LustreError: 104863:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5194.636281] LustreError: 104863:0:(namei.c:956:ll_intent_lock()) Skipped 5 previous similar messages [ 5194.851489] Lustre: Unmounted lustre-client [ 5230.039744] Key type lgssc unregistered [ 5230.522776] LNet: 105267:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5230.546817] LNetError: 105267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5231.591840] LNet: Removed LNI 192.168.201.7@tcp [ 5232.678406] Key type .llcrypt unregistered [ 5232.687553] Key type ._llcrypt unregistered [ 5252.958274] Key type ._llcrypt registered [ 5252.960321] Key type .llcrypt registered [ 5254.918763] Lustre: DEBUG MARKER: == conf-sanity test 99: Adding meta_bg option ============ 13:58:41 (1789408721) [ 5257.976722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5258.190530] alg: No test for adler32 (adler32-zlib) [ 5259.636515] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5260.139378] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 5261.999977] Key type lgssc registered [ 5263.882516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5303.139218] Lustre: DEBUG MARKER: == conf-sanity test 100: check lshowmount lists MGS, MDT, OST and 0@lo ========================================================== 13:59:30 (1789408770) [ 5421.501626] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5429.199732] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5454.017353] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5455.597770] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5455.867114] LustreError: 111123:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5455.891172] LustreError: 111123:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5455.924058] LustreError: 111123:0:(dcache.c:176:ll_intent_release()) intent 00000000fc2b89d2 released [ 5455.936787] LustreError: 111123:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5455.973791] LustreError: 111123:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](0000000078a0223c), intent=open|creat [ 5455.986815] LustreError: 111123:0:(namei.c:1744:ll_create_it()) inode 00000000872f9996 need_sync_to_mds [0x200000402:0x1:0x0] [ 5459.740301] LustreError: 111176:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5459.747187] LustreError: 111176:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 12 previous similar messages [ 5459.778302] LustreError: 111176:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5459.796522] LustreError: 111176:0:(namei.c:956:ll_intent_lock()) Skipped 10 previous similar messages [ 5461.687372] LustreError: 111185:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5461.703065] LustreError: 111185:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5461.872996] Lustre: Unmounted lustre-client [ 5489.828463] Key type lgssc unregistered [ 5490.044233] LNet: 111587:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5490.056151] LNetError: 111587:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5490.069930] LNet: Removed LNI 192.168.201.7@tcp [ 5490.857303] Key type .llcrypt unregistered [ 5490.862627] Key type ._llcrypt unregistered [ 5506.878550] Key type ._llcrypt registered [ 5506.895860] Key type .llcrypt registered [ 5508.222963] Lustre: DEBUG MARKER: == conf-sanity test 101a: Race MDT->OST reconnection with create ========================================================== 14:02:55 (1789408975) [ 5511.099570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5511.132192] alg: No test for adler32 (adler32-zlib) [ 5512.253197] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5512.559554] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 5514.275228] Key type lgssc registered [ 5515.679888] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5570.300308] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5576.749910] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5601.649836] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5604.457211] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5606.588700] LustreError: 114732:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5606.632078] LustreError: 114732:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5606.714330] LustreError: 114749:0:(dcache.c:176:ll_intent_release()) intent 00000000dbfb3937 released [ 5606.726960] LustreError: 114749:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 5611.663460] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5611.683634] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 9 previous similar messages [ 5611.726721] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5611.741574] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 8 previous similar messages [ 5611.823931] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5611.888978] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-0, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5611.907366] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000cb01f18a need_sync_to_mds [0x200000bd1:0x2:0x0] [ 5611.928750] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 000000008172a55a released [ 5611.936243] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 1 previous similar message [ 5613.081912] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5613.101831] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 5613.109752] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-1, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5613.133421] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000b09b3c29 need_sync_to_mds [0x200000bd1:0x3:0x0] [ 5613.151099] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000387211c8 released [ 5613.157887] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5613.168949] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 5 previous similar messages [ 5613.206053] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5613.216359] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 2 previous similar messages [ 5615.065705] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-47, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5615.093745] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 45 previous similar messages [ 5615.106265] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000dba56f64 need_sync_to_mds [0x200000bd1:0x31:0x0] [ 5615.119745] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 45 previous similar messages [ 5615.147123] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5615.163718] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 92 previous similar messages [ 5615.182834] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5615.194355] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 139 previous similar messages [ 5615.201877] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5615.216552] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 46 previous similar messages [ 5615.254296] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 000000001f45a22f released [ 5615.262634] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 46 previous similar messages [ 5617.182087] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-89, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5617.190865] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 41 previous similar messages [ 5617.201568] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000211514d4 need_sync_to_mds [0x200000bd1:0x5b:0x0] [ 5617.205938] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 41 previous similar messages [ 5617.251728] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5617.267893] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 42 previous similar messages [ 5619.202352] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5619.216702] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 156 previous similar messages [ 5619.226712] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5619.248219] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 234 previous similar messages [ 5619.286664] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 000000009a69651d released [ 5619.304511] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 78 previous similar messages [ 5621.201165] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-152, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5621.215113] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 62 previous similar messages [ 5621.226516] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000006338b4d6 need_sync_to_mds [0x200000bd1:0x9a:0x0] [ 5621.244811] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 62 previous similar messages [ 5621.284793] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5621.296109] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 64 previous similar messages [ 5627.689117] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5627.700674] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 247 previous similar messages [ 5627.707599] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000b9380f50 released [ 5627.712777] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 113 previous similar messages [ 5627.717777] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5627.728844] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 364 previous similar messages [ 5629.778466] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-297, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5629.797958] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 144 previous similar messages [ 5629.804662] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000001e58f2c9 need_sync_to_mds [0x200000bd1:0x12b:0x0] [ 5629.813575] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 144 previous similar messages [ 5629.832542] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5629.841282] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 149 previous similar messages [ 5643.702232] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5643.716064] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 563 previous similar messages [ 5643.744359] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 000000002e5c919e released [ 5643.758147] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 273 previous similar messages [ 5643.793195] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5643.805769] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 839 previous similar messages [ 5645.790203] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-546, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5645.808430] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 248 previous similar messages [ 5645.828486] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000005940412a need_sync_to_mds [0x200000bd1:0x224:0x0] [ 5645.841139] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 248 previous similar messages [ 5645.860251] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5645.874536] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 257 previous similar messages [ 5676.463331] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5676.479138] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 1275 previous similar messages [ 5676.486613] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000fe53974f released [ 5676.494388] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 605 previous similar messages [ 5676.508248] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5676.524585] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1889 previous similar messages [ 5679.804609] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5679.812935] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 599 previous similar messages [ 5682.890425] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-1126, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5682.903829] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 579 previous similar messages [ 5682.913422] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000002ebc2424 need_sync_to_mds [0x200000bd1:0x468:0x0] [ 5682.938114] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 579 previous similar messages [ 5740.865584] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 5740.891063] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 2091 previous similar messages [ 5740.925597] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 000000002055d27a released [ 5740.944821] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 1005 previous similar messages [ 5740.969327] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5740.998480] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3107 previous similar messages [ 5744.123449] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5744.133868] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1064 previous similar messages [ 5747.291658] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-2188, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5747.307497] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 1061 previous similar messages [ 5747.312383] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000ec01628f need_sync_to_mds [0x200000bd1:0x88e:0x0] [ 5747.327534] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 1061 previous similar messages [ 5868.884153] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5868.897870] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 3966 previous similar messages [ 5869.940161] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000862bad69 released [ 5869.977659] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 1895 previous similar messages [ 5869.995815] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5870.004293] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 5885 previous similar messages [ 5872.201996] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5872.209047] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1949 previous similar messages [ 5876.283925] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-4125, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 5876.298703] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 1936 previous similar messages [ 5876.310293] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000009204afeb need_sync_to_mds [0x200000bd1:0x101f:0x0] [ 5876.321983] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 1936 previous similar messages [ 6124.885363] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 6124.899969] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 7635 previous similar messages [ 6125.999698] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 0000000025634816 released [ 6126.038482] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 3633 previous similar messages [ 6126.055536] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6126.075738] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 11315 previous similar messages [ 6128.575291] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 6128.592868] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 3801 previous similar messages [ 6132.776803] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-7713, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 6132.928045] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 3587 previous similar messages [ 6132.941977] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 00000000cad9dec6 need_sync_to_mds [0x200000bd1:0x1e23:0x0] [ 6132.958814] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 3587 previous similar messages [ 6636.906990] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 6636.912411] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 13890 previous similar messages [ 6638.436304] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000aea2393e released [ 6638.464073] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 6640 previous similar messages [ 6638.498036] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6638.512983] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 20680 previous similar messages [ 6640.976519] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 6641.000560] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 6958 previous similar messages [ 6645.169994] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-14354, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 6645.187705] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 6640 previous similar messages [ 6645.199192] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000002049edad need_sync_to_mds [0x200000bd1:0x3814:0x0] [ 6645.207264] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 6640 previous similar messages [ 7236.906797] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) intent lock 3 on i1 [0x200000bd1:0x1:0x0] suppgids 0 -1: rc 0 [ 7236.912853] LustreError: 114962:0:(namei.c:956:ll_intent_lock()) Skipped 64423 previous similar messages [ 7238.442191] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) intent 00000000129ee9d2 released [ 7238.450484] LustreError: 114962:0:(dcache.c:176:ll_intent_release()) Skipped 32133 previous similar messages [ 7238.502809] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7238.535480] LustreError: 114962:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 96641 previous similar messages [ 7240.998734] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 7241.023288] LustreError: 114962:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 32318 previous similar messages [ 7245.192783] LustreError: 114962:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101a.conf-sanity-46724, dir=[0x200000bd1:0x1:0x0](0000000073049f51), intent=open|creat [ 7245.211676] LustreError: 114962:0:(namei.c:1721:ll_create_it()) Skipped 32369 previous similar messages [ 7245.223531] LustreError: 114962:0:(namei.c:1744:ll_create_it()) inode 000000006669dd04 need_sync_to_mds [0x200000bd1:0xb686:0x0] [ 7245.238193] LustreError: 114962:0:(namei.c:1744:ll_create_it()) Skipped 32369 previous similar messages [ 7836.913527] LustreError: 123590:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7836.925750] LustreError: 123590:0:(namei.c:956:ll_intent_lock()) Skipped 78384 previous similar messages [ 7838.448415] LustreError: 123590:0:(dcache.c:176:ll_intent_release()) intent 0000000083c3b747 released [ 7838.467254] LustreError: 123590:0:(dcache.c:176:ll_intent_release()) Skipped 39209 previous similar messages [ 7838.504658] LustreError: 123590:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7838.510713] LustreError: 123590:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 117625 previous similar messages [ 8026.497091] Lustre: Unmounted lustre-client [ 8058.925588] Key type lgssc unregistered [ 8059.177393] LNet: 124187:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8059.209851] LNetError: 124187:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8059.263825] LNet: Removed LNI 192.168.201.7@tcp [ 8060.109489] Key type .llcrypt unregistered [ 8060.111293] Key type ._llcrypt unregistered [ 8078.727564] Key type ._llcrypt registered [ 8078.730624] Key type .llcrypt registered [ 8080.382533] Lustre: DEBUG MARKER: == conf-sanity test 101b: Race events DISCONNECT and ACTIVE in osp ========================================================== 14:45:46 (1789411546) [ 8082.892661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8083.124483] alg: No test for adler32 (adler32-zlib) [ 8084.372733] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 8084.546731] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 8086.258866] Key type lgssc registered [ 8087.899666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8145.565982] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8152.688520] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8183.230614] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8185.022623] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8187.559569] LustreError: 127322:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8187.578621] LustreError: 127322:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8187.687508] LustreError: 127338:0:(dcache.c:176:ll_intent_release()) intent 000000009cbc8dfe released [ 8187.698325] LustreError: 127338:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 8187.739418] LustreError: 127346:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8195.058568] Lustre: lustre-OST0000-osc-ffff924a48e0e000: Connection to lustre-OST0000 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8251.760156] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8263.210508] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 8 sec [ 8269.296285] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff924a48e0e000.ost_server_uuid 50 [ 8270.874941] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff924a48e0e000.ost_server_uuid in FULL state after 0 sec [ 8271.260471] LustreError: 128500:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8271.266889] LustreError: 128500:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 16 previous similar messages [ 8271.272161] LustreError: 128500:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8271.278869] LustreError: 128500:0:(namei.c:956:ll_intent_lock()) Skipped 13 previous similar messages [ 8271.301905] LustreError: 128500:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8271.318868] LustreError: 128500:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1 previous similar message [ 8271.390716] LustreError: 128500:0:(namei.c:1721:ll_create_it()) VFS Op:name=f101b.conf-sanity, dir=[0x2000013a1:0x1:0x0](00000000872f9996), intent=open|creat [ 8271.412511] LustreError: 128500:0:(namei.c:1744:ll_create_it()) inode 00000000211514d4 need_sync_to_mds [0x2000013a1:0x3:0x0] [ 8271.426452] LustreError: 128500:0:(dcache.c:176:ll_intent_release()) intent 000000002b133770 released [ 8271.439313] LustreError: 128500:0:(dcache.c:176:ll_intent_release()) Skipped 3 previous similar messages [ 8273.851623] LustreError: 128518:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8273.861309] LustreError: 128518:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 8273.870983] LustreError: 128518:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8273.877915] LustreError: 128518:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 8274.003050] Lustre: Unmounted lustre-client [ 8302.223062] Key type lgssc unregistered [ 8302.740506] LNet: 128921:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8302.757049] LNetError: 128921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8302.821312] LNet: Removed LNI 192.168.201.7@tcp [ 8304.426117] Key type .llcrypt unregistered [ 8304.427583] Key type ._llcrypt unregistered [ 8328.447736] Key type ._llcrypt registered [ 8328.455080] Key type .llcrypt registered [ 8330.109130] Lustre: DEBUG MARKER: SKIP: conf-sanity test_102 skipping excluded test 102 [ 8332.277327] Lustre: DEBUG MARKER: == conf-sanity test 103: rename filesystem name ========== 14:49:58 (1789411798) [ 8340.978753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8341.041134] alg: No test for adler32 (adler32-zlib) [ 8342.185670] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 8342.516456] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 8344.239256] Key type lgssc registered [ 8345.900666] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8449.578551] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8449.855407] LustreError: 131873:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8449.886206] LustreError: 131873:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8452.113754] LustreError: 131926:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8452.132709] LustreError: 131926:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8456.232489] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8465.710316] LustreError: 129709:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8465.733185] LustreError: 129709:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 8465.752464] LustreError: 129709:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8465.783947] LustreError: 129709:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 8465.870697] LustreError: 132350:0:(dcache.c:176:ll_intent_release()) intent ffffa7eb47f73b20 released [ 8465.905876] LustreError: 132350:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 8466.670681] LustreError: 132350:0:(dcache.c:176:ll_intent_release()) intent ffffa7eb47f73b20 released [ 8466.679444] LustreError: 132350:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 8466.884853] LustreError: 132351:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 8466.983207] LustreError: 129709:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8467.502560] LustreError: 132362:0:(namei.c:1721:ll_create_it()) VFS Op:name=test-framework.sh, dir=[0x240001b70:0x1:0x0](ffff924a73562a08), intent=open|creat [ 8467.523902] LustreError: 132362:0:(namei.c:1744:ll_create_it()) inode ffff924a7357d348 need_sync_to_mds [0x240001b70:0x2:0x0] [ 8475.123143] Lustre: lustre-OST0000-osc-ffff924a610ab000: disconnect after 23s idle [ 8476.817509] LustreError: 132398:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8476.829782] LustreError: 132398:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 74 previous similar messages [ 8476.838860] LustreError: 132398:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8476.849032] LustreError: 132398:0:(namei.c:956:ll_intent_lock()) Skipped 57 previous similar messages [ 8476.946186] LustreError: 132398:0:(dcache.c:176:ll_intent_release()) intent ffffa7eb48023b20 released [ 8476.954341] LustreError: 132398:0:(dcache.c:176:ll_intent_release()) Skipped 13 previous similar messages [ 8477.014875] LustreError: 132398:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8477.036123] LustreError: 132398:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 2 previous similar messages [ 8477.087401] LustreError: 132398:0:(namei.c:1721:ll_create_it()) VFS Op:name=d0, dir=[0x240001b70:0x1:0x0](ffff924a73562a08), intent=open|creat [ 8477.106747] LustreError: 132398:0:(namei.c:1744:ll_create_it()) inode ffff924a7933c2c8 need_sync_to_mds [0x240001b70:0x3:0x0] [ 8479.241931] Lustre: Unmounted lustre-client [ 8603.340814] Lustre: Mounted mylustre-client - version 2.17.58_39_gb3cb314 [ 8603.587262] LustreError: 134244:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8603.594217] LustreError: 134244:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 16 previous similar messages [ 8603.636253] LustreError: 134244:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8603.647846] LustreError: 134244:0:(namei.c:956:ll_intent_lock()) Skipped 14 previous similar messages [ 8609.192358] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8613.610834] LustreError: 134672:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8613.620237] LustreError: 134672:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [ 8613.628505] LustreError: 134672:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8613.640719] LustreError: 134672:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 8613.770271] LustreError: 134672:0:(dcache.c:176:ll_intent_release()) intent ffffa7eb47e8bb20 released [ 8613.786196] LustreError: 134672:0:(dcache.c:176:ll_intent_release()) Skipped 3 previous similar messages [ 8617.712454] LustreError: 134700:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8617.777675] LustreError: 134700:0:(dcache.c:176:ll_intent_release()) intent ffff924a5fab16c0 released [ 8617.799918] LustreError: 134700:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 8617.811408] LustreError: 134700:0:(file.c:975:ll_kernel_to_mds_open_flags()) Skipped 2 previous similar messages [ 8621.416425] LustreError: 134718:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8621.496307] LustreError: 134718:0:(namei.c:1721:ll_create_it()) VFS Op:name=f0, dir=[0x240001b70:0x1:0x0](ffff924a73565b88), intent=open|creat [ 8621.511383] LustreError: 134718:0:(namei.c:1744:ll_create_it()) inode ffff924a735600c8 need_sync_to_mds [0x240002340:0x1:0x0] [ 8624.279904] Lustre: Unmounted mylustre-client [ 8765.986031] Lustre: Mounted tfs-client - version 2.17.58_39_gb3cb314 [ 8766.367439] LustreError: 136570:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8766.382671] LustreError: 136570:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 25 previous similar messages [ 8766.415059] LustreError: 136570:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8766.429901] LustreError: 136570:0:(namei.c:956:ll_intent_lock()) Skipped 21 previous similar messages [ 8773.688047] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8780.143193] LustreError: 136998:0:(dcache.c:176:ll_intent_release()) intent ffffa7eb47e43b20 released [ 8780.161960] LustreError: 136998:0:(dcache.c:176:ll_intent_release()) Skipped 4 previous similar messages [ 8784.286417] LustreError: 137027:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 8784.321784] LustreError: 137027:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 8786.431216] Lustre: Unmounted tfs-client [ 8927.439430] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8927.612663] LustreError: 138876:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8927.618661] LustreError: 138876:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 16 previous similar messages [ 8927.634925] LustreError: 138876:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8927.643855] LustreError: 138876:0:(namei.c:956:ll_intent_lock()) Skipped 14 previous similar messages [ 8933.007781] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8944.565811] Lustre: DEBUG MARKER: == conf-sanity test 104a: Make sure user defined options are reflected in mount ========================================================== 15:00:11 (1789412411) [ 8947.686711] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 8957.988397] Lustre: Unmounted lustre-client [ 9132.232945] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 9144.997802] Lustre: DEBUG MARKER: == conf-sanity test 104b: Mount uses last flock argument ========================================================== 15:03:31 (1789412611) [ 9145.960299] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 9146.475113] LustreError: 143335:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9146.482670] LustreError: 143335:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 4 previous similar messages [ 9146.523202] LustreError: 143335:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 9146.535111] LustreError: 143335:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 9148.606297] Lustre: Unmounted lustre-client [ 9159.475242] Lustre: Unmounted lustre-client [ 9159.481784] Lustre: Skipped 1 previous similar message [ 9161.853532] Lustre: DEBUG MARKER: == conf-sanity test 105: check file creation for ro and rw bind mnt pt ========================================================== 15:03:48 (1789412628) [ 9164.271714] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9164.277807] Lustre: Skipped 3 previous similar messages [ 9216.193787] Key type lgssc unregistered [ 9216.477173] LNet: 144455:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9216.481271] LNetError: 144455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9216.492907] LNet: Removed LNI 192.168.201.7@tcp [ 9217.232153] Key type .llcrypt unregistered [ 9217.233413] Key type ._llcrypt unregistered [ 9310.774766] Key type ._llcrypt registered [ 9310.802995] Key type .llcrypt registered [ 9311.483731] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9311.535284] alg: No test for adler32 (adler32-zlib) [ 9312.642211] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 9312.870928] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 9314.623951] Key type lgssc registered [ 9317.122047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9419.168583] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9426.918243] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9458.148792] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9460.485660] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 9460.715416] LustreError: 148272:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9460.742675] LustreError: 148272:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 9460.800296] LustreError: 148274:0:(dcache.c:176:ll_intent_release()) intent 00000000839b2bca released [ 9460.983655] Lustre: Remounted lustre-client read-only [ 9461.134694] LustreError: 148281:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 9461.174374] LustreError: 148281:0:(namei.c:1721:ll_create_it()) VFS Op:name=f105.conf-sanity1, dir=[0x200000007:0x1:0x0](0000000078a0223c), intent=open|creat [ 9461.186889] LustreError: 148281:0:(namei.c:1744:ll_create_it()) inode 00000000deb72765 need_sync_to_mds [0x200000402:0x1:0x0] [ 9461.234654] LustreError: 148281:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9461.240309] LustreError: 148281:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 33 previous similar messages [ 9461.261360] LustreError: 143887:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 9461.268931] LustreError: 143887:0:(namei.c:956:ll_intent_lock()) Skipped 30 previous similar messages [ 9461.309412] LustreError: 143887:0:(dcache.c:176:ll_intent_release()) intent 00000000f2b671cc released [ 9461.322926] LustreError: 143887:0:(dcache.c:176:ll_intent_release()) Skipped 11 previous similar messages [ 9464.220492] LustreError: 148301:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9464.223498] LustreError: 148301:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 9464.225679] LustreError: 148301:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 9464.230127] LustreError: 148301:0:(namei.c:956:ll_intent_lock()) Skipped 5 previous similar messages [ 9464.310243] Lustre: Unmounted lustre-client [ 9497.401569] Key type lgssc unregistered [ 9497.734305] LNet: 148706:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9497.749086] LNetError: 148706:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9497.770317] LNet: Removed LNI 192.168.201.7@tcp [ 9499.212504] Key type .llcrypt unregistered [ 9499.215417] Key type ._llcrypt unregistered [ 9525.237603] Key type ._llcrypt registered [ 9525.241738] Key type .llcrypt registered [ 9527.245155] Lustre: DEBUG MARKER: SKIP: conf-sanity test_106 skipping SLOW test 106 [ 9529.442222] Lustre: DEBUG MARKER: == conf-sanity test 107: Unknown config param should not fail target mounting ========================================================== 15:09:55 (1789412995) [ 9532.731573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9533.070163] alg: No test for adler32 (adler32-zlib) [ 9534.517426] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 9534.935319] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 9536.703251] Key type lgssc registered [ 9538.511530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9608.709619] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 9617.072470] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 9647.273342] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9680.823400] Key type lgssc unregistered [ 9681.324619] LNet: 152342:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9681.333640] LNetError: 152342:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9681.359724] LNet: Removed LNI 192.168.201.7@tcp [ 9682.316090] Key type .llcrypt unregistered [ 9682.317537] Key type ._llcrypt unregistered [ 9698.048606] Key type ._llcrypt registered [ 9698.068532] Key type .llcrypt registered [ 9698.784556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9698.798676] alg: No test for adler32 (adler32-zlib) [ 9700.031446] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 9700.357076] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 9702.145708] Key type lgssc registered [ 9704.306936] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9804.102210] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 9820.091403] Key type lgssc unregistered [ 9820.457152] LNet: 154322:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9820.469531] LNetError: 154322:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9820.491474] LNet: Removed LNI 192.168.201.7@tcp [ 9821.331713] Key type .llcrypt unregistered [ 9821.333271] Key type ._llcrypt unregistered [ 9840.926126] Key type ._llcrypt registered [ 9840.933054] Key type .llcrypt registered [ 9842.561168] Lustre: DEBUG MARKER: == conf-sanity test 108a: migrate from ldiskfs to ZFS ==== 15:15:09 (1789413309) [ 9844.363695] Lustre: DEBUG MARKER: SKIP: conf-sanity test_108a zfs only test [ 9846.653393] Lustre: DEBUG MARKER: == conf-sanity test 108b: migrate from ZFS to ldiskfs ==== 15:15:13 (1789413313) [ 9862.404729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 9862.448239] alg: No test for adler32 (adler32-zlib) [ 9863.774811] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 9864.113496] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [ 9865.839727] Key type lgssc registered [ 9867.477484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9953.882416] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 9953.935412] LustreError: 156893:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9953.969497] LustreError: 156893:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 9953.995484] LustreError: 156893:0:(dcache.c:176:ll_intent_release()) intent 000000002466b027 released [ 9954.016934] LustreError: 156893:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [ 9954.279367] LustreError: 155266:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 9954.451085] LustreError: 155266:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9954.461072] LustreError: 155266:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 36 previous similar messages [ 9954.507272] LustreError: 156896:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 9954.519875] LustreError: 156896:0:(namei.c:956:ll_intent_lock()) Skipped 28 previous similar messages [ 9954.556496] LustreError: 156896:0:(namei.c:1721:ll_create_it()) VFS Op:name=foooo, dir=[0x200000007:0x1:0x0](00000000d49a26b8), intent=open|creat [ 9954.571877] LustreError: 156896:0:(namei.c:1744:ll_create_it()) inode 000000000e4fc15f need_sync_to_mds [0x200000bd1:0x1:0x0] [ 9954.577828] LustreError: 156896:0:(dcache.c:176:ll_intent_release()) intent 000000002850bc65 released [ 9954.583507] LustreError: 156896:0:(dcache.c:176:ll_intent_release()) Skipped 11 previous similar messages [ 9955.054550] LustreError: 156900:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [ 9955.115775] LustreError: 156899:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 9955.127920] LustreError: 156899:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 3 previous similar messages [ 9955.544531] LustreError: 156901:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 9955.560769] LustreError: 156901:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 34 previous similar messages [ 9955.588094] LustreError: 156902:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 9955.603880] LustreError: 156902:0:(namei.c:956:ll_intent_lock()) Skipped 27 previous similar messages [ 9955.692117] LustreError: 156903:0:(dcache.c:176:ll_intent_release()) intent 0000000087cb1898 released [ 9955.700454] LustreError: 156903:0:(dcache.c:176:ll_intent_release()) Skipped 5 previous similar messages [ 9955.719477] LustreError: 156903:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [ 9955.875329] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 9955.896940] LustreError: 156904:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff924a48ea8000: inode [0x200000bd1:0x2:0x0] mdc close failed: rc = -108 [ 9955.934146] Lustre: 155711:0:(llite_lib.c:4348:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.107@tcp:/lustre/fid: [0x240000402:0x3:0x0]/ may get corrupted (rc -108) [ 9955.940857] Lustre: 155708:0:(llite_lib.c:4348:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.107@tcp:/lustre/fid: [0x200000bd1:0x2:0x0]/ may get corrupted (rc -108) [ 9966.289094] Lustre: Unmounted lustre-client [10011.982477] Lustre: DEBUG MARKER: == conf-sanity test 108c: mkfs.lustre --mmp option enables MMP feature ========================================================== 15:17:58 (1789413478) [10120.909083] Lustre: DEBUG MARKER: == conf-sanity test 109a: test lctl clear_conf fsname ==== 15:19:47 (1789413587) [10237.742638] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10245.011755] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10272.268813] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10273.136032] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [10275.356921] LustreError: 163149:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10275.364806] LustreError: 163149:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [10275.384129] LustreError: 163149:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [10275.392585] LustreError: 163149:0:(namei.c:956:ll_intent_lock()) Skipped 9 previous similar messages [10338.632788] LustreError: 163472:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10338.640908] LustreError: 163472:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [10338.647136] LustreError: 163472:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [10338.653181] LustreError: 163472:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [10341.105407] Lustre: Unmounted lustre-client [10448.485896] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10454.928495] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10477.186241] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10483.295595] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [10503.994439] LustreError: 165452:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10503.999926] LustreError: 165452:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [10504.017934] LustreError: 165452:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [10504.026085] LustreError: 165452:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [10506.036518] Lustre: Unmounted lustre-client [10531.536983] Key type lgssc unregistered [10531.867011] LNet: 165862:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10531.872540] LNetError: 165862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10531.887705] LNet: Removed LNI 192.168.201.7@tcp [10532.632093] Key type .llcrypt unregistered [10532.634083] Key type ._llcrypt unregistered [10553.094549] Key type ._llcrypt registered [10553.101487] Key type .llcrypt registered [10554.939912] Lustre: DEBUG MARKER: == conf-sanity test 109b: test lctl clear_conf one config ========================================================== 15:27:01 (1789414021) [10595.896154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [10595.909911] alg: No test for adler32 (adler32-zlib) [10597.125857] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [10597.268360] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [10598.903552] Key type lgssc registered [10600.290970] Lustre: Echo OBD driver; http://www.lustre.org/ [10693.936217] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10702.299214] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10731.866489] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10734.624342] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [10736.627062] LustreError: 170466:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10736.646906] LustreError: 170466:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [10801.020529] LustreError: 170827:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10801.027176] LustreError: 170827:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [10801.036428] LustreError: 170827:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [10801.060481] LustreError: 170827:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [10803.160415] LustreError: 170835:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10803.167551] LustreError: 170835:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [10803.250341] Lustre: Unmounted lustre-client [10900.999724] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [10907.638144] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [10930.328750] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [10941.510502] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [10965.708855] LustreError: 172822:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [10965.714327] LustreError: 172822:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [10965.726634] LustreError: 172822:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [10965.737851] LustreError: 172822:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [10967.471343] Lustre: Unmounted lustre-client [10997.299219] Key type lgssc unregistered [10997.554683] LNet: 173234:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10997.561391] LNetError: 173234:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10997.610727] LNet: Removed LNI 192.168.201.7@tcp [10998.264981] Key type .llcrypt unregistered [10998.269858] Key type ._llcrypt unregistered [11018.463502] Key type ._llcrypt registered [11018.465489] Key type .llcrypt registered [11019.561579] Lustre: DEBUG MARKER: == conf-sanity test 110: Adding large_dir with 3-level htree ========================================================== 15:34:46 (1789414486) [11035.511100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [11035.668429] alg: No test for adler32 (adler32-zlib) [11036.933141] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [11037.292379] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [11039.047252] Key type lgssc registered [11040.618469] Lustre: Echo OBD driver; http://www.lustre.org/ [11128.881496] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [11129.113372] LustreError: 176622:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11129.125340] LustreError: 176622:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [11131.204403] LustreError: 176674:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11131.210456] LustreError: 176674:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11131.705394] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [11132.360880] LustreError: 176760:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11132.367295] LustreError: 176760:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [11132.373912] LustreError: 176760:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11132.380335] LustreError: 176760:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [11132.395338] LustreError: 176760:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [11132.515177] LustreError: 176764:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [11132.554419] LustreError: 176764:0:(namei.c:1721:ll_create_it()) VFS Op:name=f110.conf-sanity-0, dir=[0x200000401:0x1:0x0](00000000872f9996), intent=open|creat [11132.564573] LustreError: 176764:0:(namei.c:1744:ll_create_it()) inode 0000000046e0c5c9 need_sync_to_mds [0x200000401:0x2:0x0] [11132.571185] LustreError: 176764:0:(dcache.c:176:ll_intent_release()) intent 000000000628284b released [11135.614549] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11135.626517] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 15 previous similar messages [11135.642968] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11135.657510] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) Skipped 10 previous similar messages [11135.711601] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) intent 00000000bf0e164a released [11135.721438] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) Skipped 2 previous similar messages [11136.720072] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) intent 00000000afb31d1a released [11136.727804] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) Skipped 153 previous similar messages [11138.730549] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) intent 00000000bf0e164a released [11138.738171] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) Skipped 205 previous similar messages [11139.612160] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11139.620185] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1833 previous similar messages [11139.644838] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11139.653607] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) Skipped 1381 previous similar messages [11142.734733] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) intent 00000000afb31d1a released [11142.756057] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) Skipped 452 previous similar messages [11147.616337] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11147.625560] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3560 previous similar messages [11147.651254] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000401:0x1:0x0] suppgids 0 -1: rc 0 [11147.660936] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) Skipped 2667 previous similar messages [11150.738808] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) intent 00000000afb31d1a released [11150.744238] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) Skipped 858 previous similar messages [11163.616352] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11163.622668] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 7193 previous similar messages [11163.655440] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000401:0x1:0x0] suppgids 0 -1: rc 0 [11163.665840] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) Skipped 5392 previous similar messages [11166.739715] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) intent 00000000bf0e164a released [11166.751340] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) Skipped 1870 previous similar messages [11195.623671] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11195.641621] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 14720 previous similar messages [11195.658611] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11195.670224] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) Skipped 11015 previous similar messages [11198.750071] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) intent 00000000bf0e164a released [11198.761311] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) Skipped 3543 previous similar messages [11259.624705] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11259.634587] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 28229 previous similar messages [11259.662707] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11259.673242] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) Skipped 21148 previous similar messages [11262.771802] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) intent 00000000afb31d1a released [11262.782775] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) Skipped 7084 previous similar messages [11387.626712] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11387.640701] LustreError: 177001:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 57807 previous similar messages [11387.662603] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11387.671323] LustreError: 177001:0:(namei.c:956:ll_intent_lock()) Skipped 43300 previous similar messages [11390.772926] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) intent 00000000afb31d1a released [11390.785704] LustreError: 177001:0:(dcache.c:176:ll_intent_release()) Skipped 14422 previous similar messages [11643.628380] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [11643.644592] LustreError: 177004:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 69954 previous similar messages [11643.676638] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [11643.682834] LustreError: 177004:0:(namei.c:956:ll_intent_lock()) Skipped 52425 previous similar messages [11646.784614] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) intent 00000000bf0e164a released [11646.790150] LustreError: 177004:0:(dcache.c:176:ll_intent_release()) Skipped 17213 previous similar messages [11987.085187] Lustre: Unmounted lustre-client [11989.598711] Lustre: Unmounted lustre-client [12129.623564] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12137.463643] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12166.756866] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12209.001904] Lustre: DEBUG MARKER: SKIP: conf-sanity test_111 skipping SLOW test 111 [12210.904030] Lustre: DEBUG MARKER: == conf-sanity test 112a: mount OST with no_create option ========================================================== 15:54:37 (1789415677) [12254.255107] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12261.402300] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [12284.270787] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12307.930989] LustreError: 174353:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0001_UUID failed: -22 [12308.015319] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [12323.411806] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff924a45377800.ost_server_uuid 50 [12325.318689] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff924a45377800.ost_server_uuid in FULL state after 0 sec [12331.979284] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff924a45377800.ost_server_uuid 50 [12333.896190] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff924a45377800.ost_server_uuid in IDLE state after 0 sec [12334.007111] LustreError: 184279:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12334.020496] LustreError: 184279:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 56573 previous similar messages [12334.050459] LustreError: 184279:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [12334.058670] LustreError: 184279:0:(namei.c:956:ll_intent_lock()) Skipped 42430 previous similar messages [12334.090460] LustreError: 184279:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12334.096045] LustreError: 184279:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 1 previous similar message [12334.155064] LustreError: 184279:0:(namei.c:1721:ll_create_it()) VFS Op:name=f112a.conf-sanity.0, dir=[0x200000007:0x1:0x0](00000000872f9996), intent=open|creat [12334.165535] LustreError: 184279:0:(namei.c:1721:ll_create_it()) Skipped 1 previous similar message [12334.180692] LustreError: 184279:0:(namei.c:1744:ll_create_it()) inode 0000000098b8eb69 need_sync_to_mds [0x200000bd1:0x1:0x0] [12334.192577] LustreError: 184279:0:(namei.c:1744:ll_create_it()) Skipped 1 previous similar message [12334.202782] LustreError: 184279:0:(dcache.c:176:ll_intent_release()) intent 00000000f40874fd released [12334.207315] LustreError: 184279:0:(dcache.c:176:ll_intent_release()) Skipped 14018 previous similar messages [12346.523547] LustreError: 184317:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12346.541735] LustreError: 184317:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 3 previous similar messages [12346.603404] LustreError: 184317:0:(namei.c:1721:ll_create_it()) VFS Op:name=f112a.conf-sanity.2, dir=[0x200000007:0x1:0x0](00000000872f9996), intent=open|creat [12346.621980] LustreError: 184317:0:(namei.c:1721:ll_create_it()) Skipped 1 previous similar message [12346.631439] LustreError: 184317:0:(namei.c:1744:ll_create_it()) inode 0000000091941f6a need_sync_to_mds [0x200000bd1:0x3:0x0] [12346.661520] LustreError: 184317:0:(namei.c:1744:ll_create_it()) Skipped 1 previous similar message [12354.058927] Lustre: lustre-OST0001-osc-ffff924a45377800: Connection to lustre-OST0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [12361.317390] Lustre: Unmounted lustre-client [12399.140878] Key type lgssc unregistered [12399.515841] LNet: 184785:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12399.522648] LNetError: 184785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12399.551677] LNet: Removed LNI 192.168.201.7@tcp [12400.372969] Key type .llcrypt unregistered [12400.378079] Key type ._llcrypt unregistered [12417.774222] Key type ._llcrypt registered [12417.776941] Key type .llcrypt registered [12419.204451] Lustre: DEBUG MARKER: == conf-sanity test 112b: mount MDT with no_create option ========================================================== 15:58:06 (1789415886) [12421.915386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12421.959451] alg: No test for adler32 (adler32-zlib) [12423.249491] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [12423.529692] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [12425.199471] Key type lgssc registered [12426.619374] Lustre: Echo OBD driver; http://www.lustre.org/ [12466.749106] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [12505.606513] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12529.817393] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [12532.539318] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [12538.777741] LustreError: 188178:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12538.853228] LustreError: 188178:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [12539.089906] LustreError: 188181:0:(dcache.c:176:ll_intent_release()) intent 000000004b4387bd released [12539.121198] LustreError: 188181:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [12539.279983] LustreError: 188184:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12539.299839] LustreError: 188184:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [12539.359841] LustreError: 188184:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12539.381298] LustreError: 188184:0:(namei.c:956:ll_intent_lock()) Skipped 13 previous similar messages [12539.620761] LustreError: 188184:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [12539.631663] LustreError: 188184:0:(mdc_lib.c:221:mdc_create_pack()) Skipped 11 previous similar messages [12540.280220] LustreError: 188184:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12540.302292] LustreError: 188184:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 100 previous similar messages [12540.365121] LustreError: 188184:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12540.372377] LustreError: 188184:0:(namei.c:956:ll_intent_lock()) Skipped 53 previous similar messages [12540.650872] LustreError: 188184:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [12540.665917] LustreError: 188184:0:(mdc_lib.c:221:mdc_create_pack()) Skipped 63 previous similar messages [12541.198400] LustreError: 188185:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12541.306145] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12541.347959] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) intent 000000004978bb18 released [12541.367351] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) Skipped 1 previous similar message [12541.819868] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12541.829839] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 13 previous similar messages [12542.283082] LustreError: 188185:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12542.298291] LustreError: 188185:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 184 previous similar messages [12542.361976] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) intent 00000000ee917c6d released [12542.376363] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) Skipped 23 previous similar messages [12542.396923] LustreError: 188185:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12542.411412] LustreError: 188185:0:(namei.c:956:ll_intent_lock()) Skipped 97 previous similar messages [12542.845685] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12542.862741] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 23 previous similar messages [12544.367148] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) intent 000000008db17b7d released [12544.371391] LustreError: 188185:0:(dcache.c:176:ll_intent_release()) Skipped 63 previous similar messages [12544.859379] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12544.895666] LustreError: 188185:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 67 previous similar messages [12546.306733] LustreError: 188185:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12546.317122] LustreError: 188185:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 452 previous similar messages [12546.410953] LustreError: 188185:0:(namei.c:956:ll_intent_lock()) intent lock 1 on i1 [0x2000013a1:0x1:0x0] suppgids 0 -1: rc 0 [12546.425636] LustreError: 188185:0:(namei.c:956:ll_intent_lock()) Skipped 260 previous similar messages [12548.730264] LustreError: 188198:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12548.775479] LustreError: 188198:0:(dcache.c:176:ll_intent_release()) intent 00000000d94b6702 released [12548.783902] LustreError: 188198:0:(dcache.c:176:ll_intent_release()) Skipped 111 previous similar messages [12548.896954] LustreError: 188198:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12548.913753] LustreError: 188198:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 97 previous similar messages [12619.141916] LustreError: 188225:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12619.161207] LustreError: 188225:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 870 previous similar messages [12619.172250] LustreError: 188225:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12619.182892] LustreError: 188225:0:(namei.c:956:ll_intent_lock()) Skipped 491 previous similar messages [12619.205075] LustreError: 188225:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [12619.219686] LustreError: 188225:0:(mdc_lib.c:221:mdc_create_pack()) Skipped 24 previous similar messages [12623.211625] LustreError: 188225:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [12623.216169] LustreError: 188225:0:(mdc_lib.c:221:mdc_create_pack()) Skipped 69 previous similar messages [12626.100561] LustreError: 188227:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [12626.105087] LustreError: 188227:0:(mdc_lib.c:329:mdc_open_pack()) Skipped 195 previous similar messages [12626.157303] LustreError: 188227:0:(dcache.c:176:ll_intent_release()) intent 0000000008028035 released [12626.178187] LustreError: 188227:0:(dcache.c:176:ll_intent_release()) Skipped 199 previous similar messages [12626.187635] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [12626.717667] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [12626.732654] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) Skipped 11 previous similar messages [12627.741206] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [12627.761721] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) Skipped 16 previous similar messages [12629.750876] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [12629.784256] LustreError: 188227:0:(file.c:977:ll_kernel_to_mds_open_flags()) Skipped 30 previous similar messages [12633.041283] LustreError: 188232:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12633.763148] LustreError: 188232:0:(file.c:977:ll_kernel_to_mds_open_flags()) debug flags not found................... [12633.769808] LustreError: 188232:0:(file.c:977:ll_kernel_to_mds_open_flags()) Skipped 45 previous similar messages [12635.042307] LustreError: 188232:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12635.060555] LustreError: 188232:0:(file.c:975:ll_kernel_to_mds_open_flags()) Skipped 27 previous similar messages [12635.151271] LustreError: 188232:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12635.168530] LustreError: 188232:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 843 previous similar messages [12635.248386] LustreError: 188232:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12635.254402] LustreError: 188232:0:(namei.c:956:ll_intent_lock()) Skipped 486 previous similar messages [12639.064381] LustreError: 188235:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12639.071644] LustreError: 188235:0:(file.c:975:ll_kernel_to_mds_open_flags()) Skipped 81 previous similar messages [12642.244524] LustreError: 166204:0:(dcache.c:176:ll_intent_release()) intent 000000002ab9a6aa released [12642.261063] LustreError: 166204:0:(dcache.c:176:ll_intent_release()) Skipped 203 previous similar messages [12647.086856] LustreError: 188235:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [12647.105640] LustreError: 188235:0:(file.c:975:ll_kernel_to_mds_open_flags()) Skipped 70 previous similar messages [12671.281361] LustreError: 188295:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12671.287756] LustreError: 188295:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1843 previous similar messages [12671.305530] LustreError: 188295:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [12671.312619] LustreError: 188295:0:(namei.c:956:ll_intent_lock()) Skipped 891 previous similar messages [12674.419262] Lustre: Unmounted lustre-client [12707.766961] Key type lgssc unregistered [12708.166797] LNet: 188708:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12708.181479] LNetError: 188708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12708.247809] LNet: Removed LNI 192.168.201.7@tcp [12709.017782] Key type .llcrypt unregistered [12709.019141] Key type ._llcrypt unregistered [12726.350413] Key type ._llcrypt registered [12726.352550] Key type .llcrypt registered [12728.030135] Lustre: DEBUG MARKER: == conf-sanity test 113: Shadow mountpoint correctly report ro/rw for mounts ========================================================== 16:03:14 (1789416194) [12755.341657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [12755.378270] alg: No test for adler32 (adler32-zlib) [12756.709030] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [12757.054770] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [12758.727372] Key type lgssc registered [12760.296626] Lustre: Echo OBD driver; http://www.lustre.org/ [12864.643240] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [12865.005510] LustreError: 191699:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12865.061324] LustreError: 191699:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [12867.785973] LustreError: 191752:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12867.811268] LustreError: 191752:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12872.176102] Lustre: DEBUG MARKER: Using TIMEOUT=20 [12890.079958] Lustre: lustre-OST0000-osc-ffff924a45c4c000: disconnect after 22s idle [12892.356532] LustreError: 192639:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12892.362902] LustreError: 192639:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [12892.368805] LustreError: 192639:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12892.375142] LustreError: 192639:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [12894.746234] LustreError: 192640:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [12894.756614] LustreError: 192640:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [12894.942332] Lustre: Unmounted lustre-client [13023.641232] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [13023.895168] LustreError: 194427:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [13023.914327] LustreError: 194427:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [13023.943784] LustreError: 194427:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [13023.947378] LustreError: 194427:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [13030.755539] Lustre: DEBUG MARKER: Using TIMEOUT=20 [13035.686355] LustreError: 194869:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [13035.696747] LustreError: 194869:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [13035.702613] LustreError: 194869:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [13035.710563] LustreError: 194869:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [13037.757520] Lustre: Unmounted lustre-client [13084.483865] Lustre: DEBUG MARKER: SKIP: conf-sanity test_114 skipping SLOW test 114 [13086.733174] Lustre: DEBUG MARKER: SKIP: conf-sanity test_115 skipping excluded test 115 [13088.119635] Lustre: DEBUG MARKER: == conf-sanity test 116: big size MDT support ============ 16:09:15 (1789416555) [13347.259828] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13354.196904] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13380.746409] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13428.309976] Lustre: DEBUG MARKER: == conf-sanity test 117: lctl get_param return errors properly ========================================================== 16:14:54 (1789416894) [13464.133752] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13470.554797] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13495.552480] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13496.600373] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [13498.603381] LustreError: 202135:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [13498.611734] LustreError: 202135:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [13498.635165] LustreError: 202135:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [13498.643092] LustreError: 202135:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [13504.088941] Lustre: Unmounted lustre-client [13533.351789] Key type lgssc unregistered [13533.614545] LNet: 202598:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13533.624381] LNetError: 202598:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13533.656291] LNet: Removed LNI 192.168.201.7@tcp [13534.673253] Key type .llcrypt unregistered [13534.678262] Key type ._llcrypt unregistered [13555.394724] Key type ._llcrypt registered [13555.399360] Key type .llcrypt registered [13557.419667] Lustre: DEBUG MARKER: == conf-sanity test 119: writeconf on slave mdt shouldn't duplicate mdc/osp and crash ========================================================== 16:17:03 (1789417023) [13564.037325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [13564.081706] alg: No test for adler32 (adler32-zlib) [13565.255776] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [13565.677224] LNet: Added LNI 192.168.201.7@tcp [8/256/0/180] [13567.519340] Key type lgssc registered [13568.842522] Lustre: Echo OBD driver; http://www.lustre.org/ [13636.925392] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [13643.478659] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [13667.701130] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [13669.521400] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [13671.125909] LustreError: 205758:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [13671.143457] LustreError: 205758:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [13674.486560] Lustre: lustre-MDT0001-mdc-ffff924a42ec8000: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [13703.724000] LustreError: 206034:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.201.107@tcp: cfg command failed: rc = -17 [13703.730477] Lustre: cmd=cf001 0:lustre-MDT0001-mdc 1:mdc 2:lustre-clilmv_UUID [13703.730477] [13703.734590] LustreError: 205708:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [13720.555562] Lustre: lustre-MDT0001-mdc-ffff924a42ec8000: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [13744.173997] LustreError: 206340:0:(obd_config.c:761:class_setup()) mdc: device 4 already setup: rc = -17 [13744.180669] LustreError: 206340:0:(obd_config.c:866:class_detach()) OBD device 4 still set up [13744.185137] LustreError: 206340:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.201.107@tcp: cfg command failed: rc = -17 [13744.191485] Lustre: cmd=cf003 0:lustre-MDT0001-mdc 1:lustre-MDT0001_UUID 2:192.168.201.107@tcp [13744.191485] [13744.204498] LustreError: 205708:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [14048.246391] Lustre: lustre-MDT0001-mdc-ffff924a42ec8000: Connection to lustre-MDT0001 (at 192.168.201.107@tcp) was lost; in progress operations using this service will wait for recovery to complete [14076.478166] LustreError: 207735:0:(obd_config.c:2102:class_config_llog_handler()) MGC192.168.201.107@tcp: cfg command failed: rc = -17 [14076.483331] Lustre: cmd=cf014 0:lustre-clilmv 1:lustre-MDT0001_UUID 2:1 3:1 4:lustre-MDT0001-mdc_UUID [14076.483331] [14076.489159] LustreError: 205708:0:(mgc_request.c:614:do_requeue()) failed processing log: -17 [14380.205038] LustreError: 209017:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [14380.215727] LustreError: 209017:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [14380.228465] LustreError: 209017:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [14380.236913] LustreError: 209017:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [14382.336347] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [14392.680816] Lustre: Unmounted lustre-client [14484.456751] Lustre: DEBUG MARKER: == conf-sanity test 120: cross-target rename should not create bad symlinks ========================================================== 16:32:31 (1789417951) [14524.344790] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [14530.456477] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [14556.750391] Lustre: DEBUG MARKER: oleg107-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [14557.856938] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [14559.811873] LustreError: 212996:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [14559.832541] LustreError: 212996:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [14559.995614] LustreError: 213018:0:(dcache.c:176:ll_intent_release()) intent 00000000e452a2b4 released [14560.014546] LustreError: 213018:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [14560.278737] LustreError: 213027:0:(file.c:975:ll_kernel_to_mds_open_flags()) debug flags found................... [14560.504261] LustreError: 213028:0:(dcache.c:176:ll_intent_release()) intent 00000000440b412e released [14560.510403] LustreError: 213028:0:(dcache.c:176:ll_intent_release()) Skipped 3 previous similar messages [14560.518077] LustreError: 213028:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [14560.528924] LustreError: 213028:0:(mdc_lib.c:221:mdc_create_pack()) Skipped 1 previous similar message [14560.894894] LustreError: 213040:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [14560.899986] LustreError: 213040:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 36 previous similar messages [14560.905966] LustreError: 213040:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [14560.915978] LustreError: 213040:0:(namei.c:956:ll_intent_lock()) Skipped 31 previous similar messages [14562.424858] Lustre: Unmounted lustre-client [14669.904196] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 14391 sec ========= 16:35:36 (1789418136) [14672.096217] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 16:35:38 (1789418138) === [14676.249775] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 16:35:43 (1789418143) === [14717.771234] Key type lgssc unregistered [14718.076804] LNet: 216889:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14718.087173] LNetError: 216889:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14718.104488] LNet: Removed LNI 192.168.201.7@tcp [14718.676183] Key type .llcrypt unregistered [14718.679038] Key type ._llcrypt unregistered