[ 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 462217596 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001008] APIC: Switch to symmetric I/O mode setup [ 0.002275] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007506] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009019] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010015] pid_max: default: 32768 minimum: 301 [ 0.011133] LSM: Security Framework initializing [ 0.012046] Yama: becoming mindful. [ 0.013029] SELinux: Initializing. [ 0.014062] *** VALIDATE selinux *** [ 0.022465] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027381] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029151] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031139] *** VALIDATE tmpfs *** [ 0.032356] *** VALIDATE proc *** [ 0.034172] *** VALIDATE cgroup *** [ 0.034886] *** VALIDATE cgroup2 *** [ 0.035299] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036156] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038031] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.042235] debug: unmapping init [mem 0xffffffffa7259000-0xffffffffa7260fff] [ 0.045824] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046697] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047025] ... version: 2 [ 0.048012] ... bit width: 48 [ 0.049012] ... generic registers: 4 [ 0.050010] ... value mask: 0000ffffffffffff [ 0.051015] ... max period: 00007fffffffffff [ 0.052009] ... fixed-purpose events: 3 [ 0.052854] ... event mask: 000000070000000f [ 0.053233] rcu: Hierarchical SRCU implementation. [ 0.055068] smp: Bringing up secondary CPUs ... [ 0.056463] x86: Booting SMP configuration: [ 0.057031] .... node #0, CPUs: #1 #2 #3 [ 0.060194] smp: Brought up 1 node, 4 CPUs [ 0.062012] smpboot: Max logical packages: 1 [ 0.063018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.138636] node 0 deferred pages initialised in 72ms [ 0.143023] devtmpfs: initialized [ 0.144263] x86/mm: Memory block size: 128MB [ 0.146191] gcov: version magic: 0x41383552 [ 0.148254] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149150] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150361] pinctrl core: initialized pinctrl subsystem [ 0.151000] [ 0.152015] ************************************************************* [ 0.155014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.157011] ** ** [ 0.159012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161014] ** ** [ 0.163012] ** This means that this kernel is built to expose internal ** [ 0.166012] ** IOMMU data structures, which may compromise security on ** [ 0.168010] ** your system. ** [ 0.169008] ** ** [ 0.172012] ** If you see this message and you are not debugging the ** [ 0.174012] ** kernel, report this immediately to your vendor! ** [ 0.176012] ** ** [ 0.179013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.181017] ************************************************************* [ 0.183088] NET: Registered protocol family 16 [ 0.184360] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.186037] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.188067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.194020] cpuidle: using governor menu [ 0.195712] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.197445] PCI: Using configuration type 1 for base access [ 0.199130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.208192] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.210029] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.213294] cryptd: max_cpu_qlen set to 1000 [ 0.216233] ACPI: Added _OSI(Module Device) [ 0.217168] ACPI: Added _OSI(Processor Device) [ 0.219012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.220010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.223985] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.228196] ACPI: Interpreter enabled [ 0.229112] ACPI: PM: (supports S0 S3 S4 S5) [ 0.231016] ACPI: Using IOAPIC for interrupt routing [ 0.232147] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.235280] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.244548] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.246034] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.248013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.250072] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.254197] acpiphp: Slot [2] registered [ 0.255079] acpiphp: Slot [5] registered [ 0.256088] acpiphp: Slot [6] registered [ 0.257146] acpiphp: Slot [3] registered [ 0.258075] acpiphp: Slot [4] registered [ 0.259086] acpiphp: Slot [7] registered [ 0.261096] acpiphp: Slot [8] registered [ 0.262055] acpiphp: Slot [9] registered [ 0.262929] acpiphp: Slot [10] registered [ 0.264083] acpiphp: Slot [11] registered [ 0.264882] acpiphp: Slot [12] registered [ 0.265059] acpiphp: Slot [13] registered [ 0.265900] acpiphp: Slot [14] registered [ 0.267049] acpiphp: Slot [15] registered [ 0.267946] acpiphp: Slot [16] registered [ 0.269105] acpiphp: Slot [17] registered [ 0.271081] acpiphp: Slot [18] registered [ 0.272074] acpiphp: Slot [19] registered [ 0.273087] acpiphp: Slot [20] registered [ 0.274081] acpiphp: Slot [21] registered [ 0.275079] acpiphp: Slot [22] registered [ 0.276106] acpiphp: Slot [23] registered [ 0.278076] acpiphp: Slot [24] registered [ 0.279105] acpiphp: Slot [25] registered [ 0.280103] acpiphp: Slot [26] registered [ 0.282130] acpiphp: Slot [27] registered [ 0.283094] acpiphp: Slot [28] registered [ 0.284048] acpiphp: Slot [29] registered [ 0.284900] acpiphp: Slot [30] registered [ 0.286092] acpiphp: Slot [31] registered [ 0.287052] PCI host bridge to bus 0000:00 [ 0.288016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.290019] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.292017] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.294020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.296037] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.299023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.300174] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.302786] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.306146] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.312401] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.316010] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.318017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.320017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.322015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.324400] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.326699] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.328040] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.330754] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.334886] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.342807] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.347013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.351707] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.362023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.373018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.385000] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.393970] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.399015] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.403013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.416022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.424741] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.427419] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.429358] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.431309] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.433154] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.437105] iommu: Default domain type: Passthrough [ 0.438342] SCSI subsystem initialized [ 0.440122] ACPI: bus type USB registered [ 0.440874] usbcore: registered new interface driver usbfs [ 0.442060] usbcore: registered new interface driver hub [ 0.443044] usbcore: registered new device driver usb [ 0.444133] pps_core: LinuxPPS API ver. 1 registered [ 0.445007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.448055] PTP clock support registered [ 0.450180] EDAC MC: Ver: 3.0.0 [ 0.451420] PCI: Using ACPI for IRQ routing [ 0.453687] NetLabel: Initializing [ 0.454008] NetLabel: domain hash size = 128 [ 0.456010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.458076] NetLabel: unlabeled traffic allowed by default [ 0.461126] vgaarb: loaded [ 0.462266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.464149] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.470296] clocksource: Switched to clocksource kvm-clock [ 0.590542] VFS: Disk quotas dquot_6.6.0 [ 0.592031] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.593805] *** VALIDATE ramfs *** [ 0.594677] *** VALIDATE hugetlbfs *** [ 0.596477] pnp: PnP ACPI init [ 0.599684] pnp: PnP ACPI: found 6 devices [ 0.616837] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.619179] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.620674] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.622556] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.624323] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.627441] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.630198] NET: Registered protocol family 2 [ 0.633102] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.638542] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.643166] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.649185] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.653485] TCP: Hash tables configured (established 65536 bind 65536) [ 0.656502] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.659878] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.663377] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.666617] NET: Registered protocol family 1 [ 0.669909] RPC: Registered named UNIX socket transport module. [ 0.672871] RPC: Registered udp transport module. [ 0.674851] RPC: Registered tcp transport module. [ 0.676526] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.678906] NET: Registered protocol family 44 [ 0.680594] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.683104] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.685575] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.687511] PCI: CLS 0 bytes, default 64 [ 0.688601] Unpacking initramfs... [ 2.030146] debug: unmapping init [mem 0xffff8ff83cc64000-0xffff8ff83ffcffff] [ 2.033791] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.035887] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.038581] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.525495] Initialise system trusted keyrings [ 2.526832] Key type blacklist registered [ 2.529218] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.538255] zbud: loaded [ 2.541366] *** VALIDATE nfs *** [ 2.542610] *** VALIDATE nfs4 *** [ 2.544202] pstore: using deflate compression [ 2.547480] Platform Keyring initialized [ 2.635809] NET: Registered protocol family 38 [ 2.636890] Key type asymmetric registered [ 2.637667] Asymmetric key parser 'x509' registered [ 2.638602] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.640270] io scheduler mq-deadline registered [ 2.641073] io scheduler kyber registered [ 2.641934] io scheduler bfq registered [ 2.643170] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.644886] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.646520] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.648230] ACPI: Power Button [PWRF] [ 2.652627] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.658714] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.668602] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.695442] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.721887] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.727420] Non-volatile memory driver v1.3 [ 2.728462] Linux agpgart interface v0.103 [ 2.755381] virtio_blk virtio1: [vda] 149960 512-byte logical blocks (76.8 MB/73.2 MiB) [ 2.757509] vda: detected capacity change from 0 to 76779520 [ 2.767556] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.769615] vdb: detected capacity change from 0 to 1073741824 [ 2.775912] libphy: Fixed MDIO Bus: probed [ 2.800918] usbcore: registered new interface driver usbserial_generic [ 2.803202] usbserial: USB Serial support registered for generic [ 2.805794] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.810753] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.812051] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.813958] mousedev: PS/2 mouse device common for all mice [ 2.816370] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.820621] rtc_cmos 00:05: RTC can wake from S4 [ 2.823172] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.825953] rtc_cmos 00:05: registered as rtc0 [ 2.829659] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.830198] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.835448] intel_pstate: CPU model not supported [ 2.838389] hid: raw HID events driver (C) Jiri Kosina [ 2.840612] usbcore: registered new interface driver usbhid [ 2.842880] usbhid: USB HID core driver [ 2.844938] drop_monitor: Initializing network drop monitor service [ 2.847197] Initializing XFRM netlink socket [ 2.849313] NET: Registered protocol family 10 [ 2.851932] Segment Routing with IPv6 [ 2.853694] NET: Registered protocol family 17 [ 2.855204] mpls_gso: MPLS GSO support [ 2.859961] RAS: Correctable Errors collector initialized. [ 2.862291] AVX version of gcm_enc/dec engaged. [ 2.863994] AES CTR mode by8 optimization enabled [ 2.942829] sched_clock: Marking stable (2942811038, 0)->(3948073879, -1005262841) [ 2.945853] registered taskstats version 1 [ 2.948102] Loading compiled-in X.509 certificates [ 2.949535] zswap: loaded using pool lzo/zbud [ 2.974666] Key type big_key registered [ 2.983179] Key type encrypted registered [ 2.984302] ima: No TPM chip found, activating TPM-bypass! [ 2.985421] ima: Allocated hash algorithm: sha1 [ 2.986335] ima: No architecture policies found [ 2.987290] evm: Initialising EVM extended attributes: [ 2.988290] evm: security.selinux [ 2.988930] evm: security.ima [ 2.989522] evm: security.capability [ 2.990239] evm: HMAC attrs: 0x1 [ 2.991640] rtc_cmos 00:05: setting system clock to 2026-09-14 16:32:27 UTC (1789403547) [ 2.995526] debug: unmapping init [mem 0xffffffffa8203000-0xffffffffa83fffff] [ 2.997308] debug: unmapping init [mem 0xffffffffa6f82000-0xffffffffa7258fff] [ 3.005104] Write protecting the kernel read-only data: 28672k [ 3.007039] debug: unmapping init [mem 0xffffffffa5603000-0xffffffffa57fffff] [ 3.008718] debug: unmapping init [mem 0xffffffffa5f14000-0xffffffffa5ffffff] [ 3.030892] 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.036881] systemd[1]: Detected virtualization kvm. [ 3.038273] systemd[1]: Detected architecture x86-64. [ 3.039488] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.066327] systemd[1]: No hostname configured. [ 3.067714] systemd[1]: Set hostname to . [ 3.069605] random: systemd: uninitialized urandom read (16 bytes read) [ 3.071534] systemd[1]: Initializing machine ID from random generator. [ 3.190970] random: systemd: uninitialized urandom read (16 bytes read) [ 3.195065] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.199880] random: systemd: uninitialized urandom read (16 bytes read) [ 3.203118] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.212697] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.778952] device-mapper: uevent: version 1.0.3 [ 3.781468] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.434657] virtio_net virtio0 ens2: renamed from eth0 [ 4.521587] scsi host0: ata_piix [ 4.537963] scsi host1: ata_piix [ 4.539944] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.543516] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.622646] dracut-initqueue[592]: RTNETLINK answers: File exists [ 9.493747] random: crng init done [ 9.495323] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.678694] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ 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 target Swap. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 10.774785] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.056592] SELinux: Disabled at runtime. [ 11.116989] 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.127403] systemd[1]: Detected virtualization kvm. [ 11.129789] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.768066] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.771966] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.778993] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.786038] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.792524] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.800680] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.809801] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ 11.950160] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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 udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.312899] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.666107] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.734106] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.866459] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.879668] EDAC sbridge: Ver: 1.1.2 [ 13.897362] Key type dns_resolver registered [ 14.216860] NFS: Registering the id_resolver key type [ 14.220073] Key type id_resolver registered [ 14.222743] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg114-client login: [ 69.704195] hrtimer: interrupt took 6381708 ns [ 75.154938] libcfs: loading out-of-tree module taints kernel. [ 75.432671] Key type ._llcrypt registered [ 75.434292] Key type .llcrypt registered [ 76.148436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 76.162668] alg: No test for adler32 (adler32-zlib) [ 78.015830] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 79.195967] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 81.159320] Key type lgssc registered [ 83.140479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 261.894643] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 262.091584] LustreError: 6202:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 262.113820] LustreError: 6202:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 264.577348] LustreError: 6254:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 264.582617] LustreError: 6254:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 267.452672] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 280.991629] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing check_logdir /tmp/testlogs/ [ 286.188714] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing yml_node [ 287.711932] Lustre: lustre-OST0000-osc-ffff8ff888c00800: disconnect after 23s idle [ 290.312601] Lustre: DEBUG MARKER: Client: 2.17.58.39 [ 292.728742] Lustre: DEBUG MARKER: MDS: 2.17.58.39 [ 295.204363] Lustre: DEBUG MARKER: OSS: 2.17.58.39 [ 296.682214] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Mon Sep 14 12:37:19 EDT 2026 [ 313.962838] Lustre: DEBUG MARKER: excepting tests: 21e 32b 32c 32newtarball [ 316.299879] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 114 [ 318.177303] LustreError: 9012:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 318.190220] LustreError: 9012:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 318.194831] LustreError: 9012:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 318.202760] LustreError: 9012:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 320.312918] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 330.669327] Lustre: Unmounted lustre-client [ 459.210584] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 465.585364] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 488.914917] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 536.628852] Lustre: DEBUG MARKER: == conf-sanity test 0: single mount setup ================ 12:41:19 (1789404079) [ 574.357061] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 582.757827] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 610.477762] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 616.537137] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 618.600521] LustreError: 14223:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 618.618483] LustreError: 14223:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 618.767245] LustreError: 14248:0:(dcache.c:176:ll_intent_release()) intent ffffaed4c1d67930 released [ 618.805897] LustreError: 14248:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 618.874115] LustreError: 14248:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](ffff8ff889a55b88), intent=open|creat [ 618.882595] LustreError: 14248:0:(namei.c:1744:ll_create_it()) inode ffff8ff889933a88 need_sync_to_mds [0x200000bd1:0x1:0x0] [ 620.897752] Lustre: Unmounted lustre-client [ 649.138393] Key type lgssc unregistered [ 649.387463] LNet: 14689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 649.398475] LNetError: 14689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 649.428477] LNet: Removed LNI 192.168.201.14@tcp [ 650.309642] Key type .llcrypt unregistered [ 650.315163] Key type ._llcrypt unregistered [ 668.302252] Key type ._llcrypt registered [ 668.306896] Key type .llcrypt registered [ 669.690968] Lustre: DEBUG MARKER: == conf-sanity test 1: start up ost twice (should return errors) ========================================================== 12:43:32 (1789404212) [ 671.988401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 672.103622] alg: No test for adler32 (adler32-zlib) [ 673.278245] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 673.525420] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 675.216263] Key type lgssc registered [ 676.498610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 728.065559] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 735.494247] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 760.602139] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 773.215373] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 773.506622] LustreError: 17931:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 773.522620] LustreError: 17931:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 773.536257] LustreError: 17931:0:(dcache.c:176:ll_intent_release()) intent 000000000ab20482 released [ 773.557248] LustreError: 17931:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 773.615830] LustreError: 17931:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000fd7dba5f), intent=open|creat [ 773.629141] LustreError: 17931:0:(namei.c:1744:ll_create_it()) inode 000000007333ba74 need_sync_to_mds [0x2000013a1:0x1:0x0] [ 774.146977] LustreError: 17955:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 774.152827] LustreError: 17955:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 12 previous similar messages [ 774.165588] LustreError: 17955:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 774.170574] LustreError: 17955:0:(namei.c:956:ll_intent_lock()) Skipped 10 previous similar messages [ 775.660471] LustreError: 17963:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 775.667349] LustreError: 17963:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 775.855662] Lustre: Unmounted lustre-client [ 799.067248] Key type lgssc unregistered [ 799.406201] LNet: 18364:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 799.422931] LNetError: 18364:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 799.449243] LNet: Removed LNI 192.168.201.14@tcp [ 800.303309] Key type .llcrypt unregistered [ 800.305663] Key type ._llcrypt unregistered [ 814.050373] Key type ._llcrypt registered [ 814.052674] Key type .llcrypt registered [ 815.532632] Lustre: DEBUG MARKER: == conf-sanity test 2: start up mds twice (should return err) ========================================================== 12:45:58 (1789404358) [ 818.193938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 818.206717] alg: No test for adler32 (adler32-zlib) [ 819.376754] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 819.626904] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 821.271256] Key type lgssc registered [ 822.414447] Lustre: Echo OBD driver; http://www.lustre.org/ [ 872.241442] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 877.746411] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 907.763557] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 920.129980] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 920.362250] LustreError: 21581:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 920.379352] LustreError: 21581:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 920.395431] LustreError: 21581:0:(dcache.c:176:ll_intent_release()) intent 0000000074ff8361 released [ 920.407591] LustreError: 21581:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 920.463671] LustreError: 21581:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](0000000072c45cf6), intent=open|creat [ 920.471755] LustreError: 21581:0:(namei.c:1744:ll_create_it()) inode 000000005bbc517f need_sync_to_mds [0x200001b71:0x1:0x0] [ 922.301628] LustreError: 21612:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 922.306782] LustreError: 21612:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [ 922.312229] LustreError: 21612:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 922.318908] LustreError: 21612:0:(namei.c:956:ll_intent_lock()) Skipped 11 previous similar messages [ 922.416838] Lustre: Unmounted lustre-client [ 950.245804] Key type lgssc unregistered [ 950.574872] LNet: 22016:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 950.582729] LNetError: 22016:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 950.598070] LNet: Removed LNI 192.168.201.14@tcp [ 951.304457] Key type .llcrypt unregistered [ 951.307059] Key type ._llcrypt unregistered [ 967.042313] Key type ._llcrypt registered [ 967.045521] Key type .llcrypt registered [ 968.494365] Lustre: DEBUG MARKER: == conf-sanity test 3: mount client twice (should return err) ========================================================== 12:48:31 (1789404511) [ 971.423164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 971.455381] alg: No test for adler32 (adler32-zlib) [ 972.727241] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 973.085896] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 974.801141] Key type lgssc registered [ 976.186643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1039.816593] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1047.250627] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1073.832907] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1075.592304] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1077.747705] LustreError: 25152:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1077.766075] LustreError: 25152:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1078.130276] LustreError: 25191:0:(dcache.c:176:ll_intent_release()) intent 00000000a5b894da released [ 1078.157221] LustreError: 25191:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1078.195092] LustreError: 25191:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](000000005896a7a2), intent=open|creat [ 1078.218435] LustreError: 25191:0:(namei.c:1744:ll_create_it()) inode 00000000cce762d9 need_sync_to_mds [0x200002341:0x1:0x0] [ 1078.283983] LustreError: 25191:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1078.299142] LustreError: 25191:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 12 previous similar messages [ 1078.468841] LustreError: 25198:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1078.475075] LustreError: 25198:0:(namei.c:956:ll_intent_lock()) Skipped 12 previous similar messages [ 1080.201165] LustreError: 25224:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1080.207835] LustreError: 25224:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 1080.212573] LustreError: 25224:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1080.217704] LustreError: 25224:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 1080.337453] Lustre: Unmounted lustre-client [ 1110.032180] Key type lgssc unregistered [ 1110.348132] LNet: 25626:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1110.365260] LNetError: 25626:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1110.379829] LNet: Removed LNI 192.168.201.14@tcp [ 1111.213662] Key type .llcrypt unregistered [ 1111.219870] Key type ._llcrypt unregistered [ 1128.079659] Key type ._llcrypt registered [ 1128.081231] Key type .llcrypt registered [ 1129.408941] Lustre: DEBUG MARKER: == conf-sanity test 4: force cleanup ost, then cleanup === 12:51:12 (1789404672) [ 1131.664492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1131.690297] alg: No test for adler32 (adler32-zlib) [ 1132.771250] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1132.999730] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 1134.705417] Key type lgssc registered [ 1135.999639] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1187.430711] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1192.835679] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1213.607381] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1216.282573] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1218.746168] LustreError: 28756:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1218.778624] LustreError: 28756:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1221.803869] LustreError: 28773:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1221.828900] LustreError: 28773:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 1221.839466] LustreError: 28773:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 1221.861385] LustreError: 28773:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 1221.894547] LustreError: 28773:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1221.960171] LustreError: 28773:0:(namei.c:1721:ll_create_it()) VFS Op:name=f4.conf-sanity, dir=[0x200000007:0x1:0x0](00000000e1f21a9a), intent=open|creat [ 1221.974473] LustreError: 28773:0:(namei.c:1744:ll_create_it()) inode 00000000459a6e1b need_sync_to_mds [0x200002b11:0x1:0x0] [ 1222.021796] LustreError: 28773:0:(dcache.c:176:ll_intent_release()) intent 000000009fa30dc9 released [ 1226.744449] Lustre: lustre-OST0000-osc-ffff8ff891f6b000: Connection to lustre-OST0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1232.378723] LustreError: 28836:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1232.384682] LustreError: 28836:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 5 previous similar messages [ 1232.395772] LustreError: 28836:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1232.402049] LustreError: 28836:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 1234.300935] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1244.723060] Lustre: Unmounted lustre-client [ 1276.333113] Key type lgssc unregistered [ 1276.730290] LNet: 29240:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1276.737329] LNetError: 29240:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1276.758868] LNet: Removed LNI 192.168.201.14@tcp [ 1277.668106] Key type .llcrypt unregistered [ 1277.675526] Key type ._llcrypt unregistered [ 1294.652158] Key type ._llcrypt registered [ 1294.654205] Key type .llcrypt registered [ 1296.225988] Lustre: DEBUG MARKER: == conf-sanity test 5a: force cleanup mds, then cleanup == 12:53:58 (1789404838) [ 1298.734397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1299.015773] alg: No test for adler32 (adler32-zlib) [ 1300.260482] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1300.601663] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 1302.279365] Key type lgssc registered [ 1303.821417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1361.866918] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1369.271381] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1392.701611] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1404.013870] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 1406.262996] LustreError: 32377:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 1406.286411] LustreError: 32377:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 1406.375101] LustreError: 32394:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 1406.431743] LustreError: 32394:0:(namei.c:1721:ll_create_it()) VFS Op:name=f5a.conf-sanity, dir=[0x200000007:0x1:0x0](0000000009c1f16b), intent=open|creat [ 1406.448903] LustreError: 32394:0:(namei.c:1744:ll_create_it()) inode 00000000f1675774 need_sync_to_mds [0x2000032e1:0x1:0x0] [ 1406.484938] LustreError: 32394:0:(dcache.c:176:ll_intent_release()) intent 000000004c1b29cd released [ 1414.127272] Lustre: lustre-MDT0000-mdc-ffff8ff886c94800: Connection to lustre-MDT0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1418.270249] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1428.611605] LustreError: MGC192.168.201.114@tcp: Connection to MGS (at 192.168.201.114@tcp) was lost; in progress operations using this service will fail [ 1428.648510] Lustre: Unmounted lustre-client [ 1468.553521] Key type lgssc unregistered [ 1468.843651] LNet: 32901:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1468.852789] LNetError: 32901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1468.889599] LNet: Removed LNI 192.168.201.14@tcp [ 1469.783381] Key type .llcrypt unregistered [ 1469.790330] Key type ._llcrypt unregistered [ 1487.216689] Key type ._llcrypt registered [ 1487.218834] Key type .llcrypt registered [ 1488.491501] Lustre: DEBUG MARKER: == conf-sanity test 5b: Try to start a client with no MGS (should return errs) ========================================================== 12:57:11 (1789405031) [ 1490.903944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1490.999201] alg: No test for adler32 (adler32-zlib) [ 1492.193423] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1492.350193] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 1494.007815] Key type lgssc registered [ 1495.347741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1585.299714] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1591.139155] LustreError: 35100:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.114@tcp: failed processing log, type 1: rc = -110 [ 1600.480646] LustreError: 35109:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 1748.961492] LustreError: MGC192.168.201.114@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 1748.992311] Lustre: Unmounted lustre-client [ 1749.001882] LustreError: 35100:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 1768.226253] Key type lgssc unregistered [ 1768.480808] LNet: 35541:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1768.493781] LNetError: 35541:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1768.537639] LNet: Removed LNI 192.168.201.14@tcp [ 1769.939418] Key type .llcrypt unregistered [ 1769.944065] Key type ._llcrypt unregistered [ 1787.657774] Key type ._llcrypt registered [ 1787.660330] Key type .llcrypt registered [ 1789.187206] Lustre: DEBUG MARKER: == conf-sanity test 5c: cleanup after failed mount (bug 2712) (should return errs) ========================================================== 13:02:12 (1789405332) [ 1792.006291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1792.160649] alg: No test for adler32 (adler32-zlib) [ 1793.315833] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1793.652179] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 1795.399190] Key type lgssc registered [ 1797.002880] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1854.063342] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 1860.811893] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 1883.918770] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 1884.459690] LustreError: 38635:0:(llite_lib.c:1555:ll_fill_super()) wrong.lustre: fsname longer than 8 characters: rc = -36 [ 1884.471911] Lustre: Unmounted wrong.lustre-client [ 1884.483508] LustreError: 38635:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -36 [ 1913.520679] Key type lgssc unregistered [ 1913.832398] LNet: 39050:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1913.837372] LNetError: 39050:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 1913.863427] LNet: Removed LNI 192.168.201.14@tcp [ 1914.673673] Key type .llcrypt unregistered [ 1914.676868] Key type ._llcrypt unregistered [ 1932.668921] Key type ._llcrypt registered [ 1932.670739] Key type .llcrypt registered [ 1934.270722] Lustre: DEBUG MARKER: == conf-sanity test 5d: mount with ost down ============== 13:04:37 (1789405477) [ 1937.912220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1937.973034] alg: No test for adler32 (adler32-zlib) [ 1939.167845] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 1939.477046] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 1941.207457] Key type lgssc registered [ 1942.905497] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2028.271502] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2066.429938] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2073.566679] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2086.783536] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2087.487774] LustreError: 42214:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2087.507928] LustreError: 42214:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2089.697858] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2099.993953] Lustre: Unmounted lustre-client [ 2130.393939] Key type lgssc unregistered [ 2130.806086] LNet: 42620:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2130.818967] LNetError: 42620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2130.888261] LNet: Removed LNI 192.168.201.14@tcp [ 2131.638203] Key type .llcrypt unregistered [ 2131.641217] Key type ._llcrypt unregistered [ 2148.889310] Key type ._llcrypt registered [ 2148.891537] Key type .llcrypt registered [ 2150.310404] Lustre: DEBUG MARKER: == conf-sanity test 5e: delayed connect, don't crash (bug 10268) ========================================================== 13:08:13 (1789405693) [ 2153.596940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2153.626560] alg: No test for adler32 (adler32-zlib) [ 2154.880242] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 2155.223700] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 2156.943256] Key type lgssc registered [ 2158.280839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2217.601636] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2224.531045] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2250.834988] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2251.497286] LustreError: 43577:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 sleeping for 10000ms [ 2256.608159] LustreError: 45705:0:(mgc_request.c:233:do_config_log_add()) MGC192.168.201.114@tcp: failed processing log, type 1: rc = -110 [ 2261.599320] LustreError: 43577:0:(niobuf.c:1106:ptl_send_rpc()) cfs_fail_timeout id 506 awake [ 2262.872662] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2263.392945] LustreError: 45751:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2263.407969] LustreError: 45751:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2265.476872] LustreError: 45760:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2265.488921] LustreError: 45760:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2265.658755] Lustre: Unmounted lustre-client [ 2294.923967] Key type lgssc unregistered [ 2295.191854] LNet: 46162:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2295.197758] LNetError: 46162:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2295.222069] LNet: Removed LNI 192.168.201.14@tcp [ 2295.905904] Key type .llcrypt unregistered [ 2295.908742] Key type ._llcrypt unregistered [ 2313.515585] Key type ._llcrypt registered [ 2313.520069] Key type .llcrypt registered [ 2314.968879] Lustre: DEBUG MARKER: == conf-sanity test 5f: mds down, cleanup after failed mount (bug 2712) ========================================================== 13:10:57 (1789405857) [ 2316.519718] Lustre: DEBUG MARKER: SKIP: conf-sanity test_5f needs separate mgs and mds [ 2318.636915] Lustre: DEBUG MARKER: == conf-sanity test 5g: handle missing debugfs =========== 13:11:01 (1789405861) [ 2320.187923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2320.233673] alg: No test for adler32 (adler32-zlib) [ 2321.594548] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 2322.054953] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 2323.784142] Key type lgssc registered [ 2325.088846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2351.184657] Lustre: DEBUG MARKER: == conf-sanity test 5h: start mdt failure at mdt_fs_setup() ========================================================== 13:11:33 (1789405893) [ 2386.524504] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2393.387685] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2418.837494] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2421.158181] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2423.647428] LustreError: 50196:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2423.687400] LustreError: 50196:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2426.367793] Lustre: lustre-MDT0000-mdc-ffff8ff886266800: Connection to lustre-MDT0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2441.699667] Lustre: 47383:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789405970/real 1789405970] req@00000000a9b23fa0 x1876328045350400/t0(0) o400->MGC192.168.201.114@tcp@192.168.201.114@tcp:26/25 lens 224/224 e 0 to 1 dl 1789405986 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2441.750581] LustreError: MGC192.168.201.114@tcp: Connection to MGS (at 192.168.201.114@tcp) was lost; in progress operations using this service will fail [ 2451.889513] Lustre: lustre-MDT0000-mdc-ffff8ff886266800: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 2452.905295] Lustre: Evicted from MGS (at 192.168.201.114@tcp) after server handle changed from 0xad91227c31eb9be8 to 0xad91227c31eb9f53 [ 2452.915379] Lustre: MGC192.168.201.114@tcp: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 2455.153406] LustreError: 50573:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2455.161215] LustreError: 50573:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 2455.178231] LustreError: 50573:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2455.186961] LustreError: 50573:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 2457.113215] LustreError: 50617:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2457.117607] LustreError: 50617:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [ 2457.124761] LustreError: 50617:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2457.133661] LustreError: 50617:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 2457.294097] Lustre: Unmounted lustre-client [ 2489.742179] Key type lgssc unregistered [ 2490.415144] LNet: 51021:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2490.436549] LNetError: 51021:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2490.469715] LNet: Removed LNI 192.168.201.14@tcp [ 2492.363638] Key type .llcrypt unregistered [ 2492.368696] Key type ._llcrypt unregistered [ 2511.721441] Key type ._llcrypt registered [ 2511.722709] Key type .llcrypt registered [ 2513.472715] Lustre: DEBUG MARKER: == conf-sanity test 5i: start mdt failure at mdt_quota_init() ========================================================== 13:14:16 (1789406056) [ 2516.798200] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2516.876132] alg: No test for adler32 (adler32-zlib) [ 2517.959510] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 2518.177151] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 2519.899415] Key type lgssc registered [ 2521.651590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2579.901373] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2586.503330] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2608.729974] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2611.614069] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2613.240328] LustreError: 54160:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2613.253668] LustreError: 54160:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2616.815232] Lustre: lustre-MDT0000-mdc-ffff8ff891f6c000: Connection to lustre-MDT0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2633.183174] Lustre: 51981:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789406161/real 1789406161] req@00000000b332ce15 x1876328250871552/t0(0) o400->MGC192.168.201.114@tcp@192.168.201.114@tcp:26/25 lens 224/224 e 0 to 1 dl 1789406177 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 2633.216766] LustreError: MGC192.168.201.114@tcp: Connection to MGS (at 192.168.201.114@tcp) was lost; in progress operations using this service will fail [ 2642.809842] Lustre: lustre-MDT0000-mdc-ffff8ff891f6c000: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 2643.842634] Lustre: Evicted from MGS (at 192.168.201.114@tcp) after server handle changed from 0xf85a9747c37b0399 to 0xf85a9747c37b0719 [ 2643.849617] Lustre: MGC192.168.201.114@tcp: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 2646.385531] LustreError: 54537:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2646.395777] LustreError: 54537:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 2646.401943] LustreError: 54537:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2646.408696] LustreError: 54537:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 2649.141877] LustreError: 54581:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2649.148528] LustreError: 54581:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [ 2649.153811] LustreError: 54581:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2649.162805] LustreError: 54581:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 2649.279908] Lustre: Unmounted lustre-client [ 2675.919673] Key type lgssc unregistered [ 2676.233963] LNet: 54983:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2676.238150] LNetError: 54983:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2676.263743] LNet: Removed LNI 192.168.201.14@tcp [ 2677.264417] Key type .llcrypt unregistered [ 2677.267266] Key type ._llcrypt unregistered [ 2692.364559] Key type ._llcrypt registered [ 2692.367119] Key type .llcrypt registered [ 2693.663814] Lustre: DEBUG MARKER: == conf-sanity test 5j: verify libcfs doesn't crash when setting debug flags ========================================================== 13:17:16 (1789406236) [ 2694.335467] Key type .llcrypt unregistered [ 2694.337077] Key type ._llcrypt unregistered [ 2701.303956] Key type ._llcrypt registered [ 2701.306291] Key type .llcrypt registered [ 2701.360273] Key type .llcrypt unregistered [ 2701.373195] Key type ._llcrypt unregistered [ 2706.930861] Key type ._llcrypt registered [ 2706.933810] Key type .llcrypt registered [ 2708.240807] Lustre: DEBUG MARKER: == conf-sanity test 6: manual umount, then mount again === 13:17:31 (1789406251) [ 2710.737793] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2710.745198] alg: No test for adler32 (adler32-zlib) [ 2711.857856] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 2712.128218] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 2713.783313] Key type lgssc registered [ 2715.480638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2777.481839] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2784.169430] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2809.675634] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2817.137178] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2819.485797] LustreError: 58904:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2819.498275] LustreError: 58904:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2819.936919] Lustre: Unmounted lustre-client [ 2820.512325] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2820.800792] LustreError: 58980:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2820.807263] LustreError: 58980:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 4 previous similar messages [ 2820.819098] LustreError: 58980:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2820.828732] LustreError: 58980:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 2820.847823] LustreError: 58980:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 2820.874966] LustreError: 58980:0:(namei.c:1721:ll_create_it()) VFS Op:name=f6.conf-sanity, dir=[0x200000007:0x1:0x0](00000000eda1f0c2), intent=open|creat [ 2820.892652] LustreError: 58980:0:(namei.c:1744:ll_create_it()) inode 0000000082fe24bf need_sync_to_mds [0x200007161:0x1:0x0] [ 2820.904675] LustreError: 58980:0:(dcache.c:176:ll_intent_release()) intent 0000000065d47477 released [ 2823.234933] LustreError: 58999:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2823.254378] LustreError: 58999:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 6 previous similar messages [ 2823.264357] LustreError: 58999:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 2823.273992] LustreError: 58999:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 2823.460628] Lustre: Unmounted lustre-client [ 2856.979615] Key type lgssc unregistered [ 2857.510475] LNet: 59402:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2857.520938] LNetError: 59402:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 2858.536668] LNet: Removed LNI 192.168.201.14@tcp [ 2860.200470] Key type .llcrypt unregistered [ 2860.202420] Key type ._llcrypt unregistered [ 2879.320624] Key type ._llcrypt registered [ 2879.322938] Key type .llcrypt registered [ 2880.547450] Lustre: DEBUG MARKER: == conf-sanity test 7: manual umount, then cleanup ======= 13:20:23 (1789406423) [ 2883.547208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2883.590165] alg: No test for adler32 (adler32-zlib) [ 2884.716330] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 2884.943512] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 2886.607609] Key type lgssc registered [ 2888.237792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2936.834613] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 2943.732299] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 2972.168824] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 2973.935463] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 2976.033635] LustreError: 62539:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 2976.074895] LustreError: 62539:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 2976.515215] Lustre: Unmounted lustre-client [ 3013.275420] Key type lgssc unregistered [ 3013.566116] LNet: 62975:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3013.570895] LNetError: 62975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3013.580164] LNet: Removed LNI 192.168.201.14@tcp [ 3014.302108] Key type .llcrypt unregistered [ 3014.303477] Key type ._llcrypt unregistered [ 3030.553718] Key type ._llcrypt registered [ 3030.557378] Key type .llcrypt registered [ 3031.862978] Lustre: DEBUG MARKER: == conf-sanity test 8: double mount setup ================ 13:22:54 (1789406574) [ 3034.678653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3034.763628] alg: No test for adler32 (adler32-zlib) [ 3035.913579] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 3036.229771] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 3037.880243] Key type lgssc registered [ 3039.103450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3090.962200] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3096.157083] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3119.426251] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3121.984925] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 3123.889641] LustreError: 66106:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3123.908527] LustreError: 66106:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 3124.257489] LustreError: 63930:0:(lmv_obd.c:211:lmv_notify()) activation of lustre-MDT0000_UUID failed: -22 [ 3124.300361] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 3124.541314] LustreError: 66169:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3124.550123] LustreError: 66169:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 3124.556520] LustreError: 66169:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 3124.563627] LustreError: 66169:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 3124.580249] LustreError: 66169:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 3124.611739] LustreError: 66169:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](000000005896a7a2), intent=open|creat [ 3124.617243] LustreError: 66169:0:(namei.c:1744:ll_create_it()) inode 0000000011ce3a5e need_sync_to_mds [0x200008101:0x1:0x0] [ 3124.625390] LustreError: 66169:0:(dcache.c:176:ll_intent_release()) intent 0000000049959abe released [ 3127.294993] LustreError: 66206:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3127.299310] LustreError: 66206:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 25 previous similar messages [ 3127.303847] LustreError: 66206:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 3127.308249] LustreError: 66206:0:(namei.c:956:ll_intent_lock()) Skipped 19 previous similar messages [ 3127.394680] Lustre: Unmounted lustre-client [ 3129.418237] Lustre: Unmounted lustre-client [ 3159.501909] Key type lgssc unregistered [ 3159.975485] LNet: 66629:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3159.996405] LNetError: 66629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3160.009821] LNet: Removed LNI 192.168.201.14@tcp [ 3161.474963] Key type .llcrypt unregistered [ 3161.476914] Key type ._llcrypt unregistered [ 3179.995265] Key type ._llcrypt registered [ 3179.997838] Key type .llcrypt registered [ 3181.178804] Lustre: DEBUG MARKER: == conf-sanity test 9: test ptldebug and subsystem for mkfs ========================================================== 13:25:24 (1789406724) [ 3184.421329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3184.476250] alg: No test for adler32 (adler32-zlib) [ 3185.677223] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 3186.070958] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 3187.792373] Key type lgssc registered [ 3189.648829] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3284.339267] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3299.760387] Lustre: DEBUG MARKER: == conf-sanity test 10a: find lctl param broken symlinks ========================================================== 13:27:22 (1789406842) [ 3339.202727] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3346.830355] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3374.872401] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3380.819943] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 3382.592255] LustreError: 70887:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3382.611595] LustreError: 70887:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 3387.251696] LustreError: 70983:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3387.265130] LustreError: 70983:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 2 previous similar messages [ 3387.284804] LustreError: 70983:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 3387.298034] LustreError: 70983:0:(namei.c:956:ll_intent_lock()) Skipped 2 previous similar messages [ 3388.600500] LustreError: 70991:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3388.604662] LustreError: 70991:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 3388.686159] Lustre: Unmounted lustre-client [ 3419.024549] Key type lgssc unregistered [ 3419.368311] LNet: 71393:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3419.388574] LNetError: 71393:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3419.411822] LNet: Removed LNI 192.168.201.14@tcp [ 3420.335226] Key type .llcrypt unregistered [ 3420.338367] Key type ._llcrypt unregistered [ 3438.100666] Key type ._llcrypt registered [ 3438.102630] Key type .llcrypt registered [ 3439.185756] Lustre: DEBUG MARKER: == conf-sanity test 11: Verify tool --version option works properly ========================================================== 13:29:42 (1789406982) [ 3458.499101] Lustre: DEBUG MARKER: == conf-sanity test 17: Verify failed mds_postsetup won't fail assertion (2936) (should return errs) ========================================================== 13:30:01 (1789407001) [ 3461.885867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3461.899647] alg: No test for adler32 (adler32-zlib) [ 3463.125900] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 3463.513915] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 3465.207386] Key type lgssc registered [ 3466.603896] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3523.893842] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3531.360359] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3554.943920] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3567.728048] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 3569.774668] LustreError: 75221:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3569.805966] LustreError: 75221:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 3569.982481] LustreError: 75244:0:(dcache.c:176:ll_intent_release()) intent 000000007a208dd9 released [ 3570.024983] LustreError: 75244:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 3570.115149] LustreError: 75244:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](0000000092b06beb), intent=open|creat [ 3570.133666] LustreError: 75244:0:(namei.c:1744:ll_create_it()) inode 00000000fa93ca41 need_sync_to_mds [0x2000090a1:0x1:0x0] [ 3570.315519] LustreError: 75258:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3570.325833] LustreError: 75258:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 10 previous similar messages [ 3570.341447] LustreError: 75258:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 3570.351347] LustreError: 75258:0:(namei.c:956:ll_intent_lock()) Skipped 9 previous similar messages [ 3572.520951] LustreError: 75283:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 3572.525115] LustreError: 75283:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 5 previous similar messages [ 3572.529293] LustreError: 75283:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 3572.535265] LustreError: 75283:0:(namei.c:956:ll_intent_lock()) Skipped 4 previous similar messages [ 3572.664780] Lustre: Unmounted lustre-client [ 3613.116737] Key type lgssc unregistered [ 3613.401687] LNet: 75689:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3613.410184] LNetError: 75689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 3613.432694] LNet: Removed LNI 192.168.201.14@tcp [ 3614.218645] Key type .llcrypt unregistered [ 3614.222887] Key type ._llcrypt unregistered [ 3628.471066] Key type ._llcrypt registered [ 3628.484204] Key type .llcrypt registered [ 3629.120846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3629.132425] alg: No test for adler32 (adler32-zlib) [ 3630.381312] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 3630.717893] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 3632.497226] Key type lgssc registered [ 3634.084686] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3720.272502] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3875.081892] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 3882.439425] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 3908.477087] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 3961.683202] Lustre: DEBUG MARKER: == conf-sanity test 18: check mkfs creates large journals ========================================================== 13:38:24 (1789407504) [ 3965.599753] Lustre: DEBUG MARKER: use device /dev/mapper/mds1_flakey with MIN=2000000 [ 4081.702582] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4087.569801] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4116.986554] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4200.316463] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4206.972489] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4231.727636] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4243.056403] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4245.428285] LustreError: 86791:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4245.483451] LustreError: 86791:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4245.737766] LustreError: 86815:0:(dcache.c:176:ll_intent_release()) intent 0000000048ebb8a5 released [ 4245.773991] LustreError: 86815:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4245.853154] LustreError: 86815:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](000000001dbf9bea), intent=open|creat [ 4245.871796] LustreError: 86815:0:(namei.c:1744:ll_create_it()) inode 00000000d800809b need_sync_to_mds [0x200000bd1:0x1:0x0] [ 4245.945100] LustreError: 86815:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4245.952062] LustreError: 86815:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 9 previous similar messages [ 4246.055212] LustreError: 86828:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4246.061996] LustreError: 86828:0:(namei.c:956:ll_intent_lock()) Skipped 9 previous similar messages [ 4248.734333] Lustre: DEBUG MARKER: Success: mkfs creates large journals. Size: 78M [ 4249.694418] LustreError: 86996:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4249.708102] LustreError: 86996:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 5 previous similar messages [ 4249.738881] LustreError: 86996:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4249.750154] LustreError: 86996:0:(namei.c:956:ll_intent_lock()) Skipped 3 previous similar messages [ 4251.764989] LustreError: 87004:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4251.770206] LustreError: 87004:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4251.883293] Lustre: Unmounted lustre-client [ 4283.018237] Key type lgssc unregistered [ 4283.411117] LNet: 87409:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4283.416708] LNetError: 87409:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4283.446087] LNet: Removed LNI 192.168.201.14@tcp [ 4284.501685] Key type .llcrypt unregistered [ 4284.511578] Key type ._llcrypt unregistered [ 4316.808265] Key type ._llcrypt registered [ 4316.813384] Key type .llcrypt registered [ 4317.371874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4317.393172] alg: No test for adler32 (adler32-zlib) [ 4318.564588] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 4318.991554] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 4320.656440] Key type lgssc registered [ 4322.629836] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4423.748953] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4430.801563] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4455.962613] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4507.522333] Lustre: DEBUG MARKER: == conf-sanity test 19a: start/stop MDS without OSTs ===== 13:47:30 (1789408050) [ 4546.555716] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4553.183534] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4575.901887] Lustre: DEBUG MARKER: == conf-sanity test 19b: start/stop OSTs without MDS ===== 13:48:38 (1789408118) [ 4649.673635] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4666.008040] Lustre: DEBUG MARKER: == conf-sanity test 20: remount ro,rw mounts work and doesn't break /etc/mtab ========================================================== 13:50:07 (1789408207) [ 4703.620262] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4711.005616] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4741.169680] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4744.099988] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 4744.350867] LustreError: 96100:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4744.364314] LustreError: 96100:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4744.375880] LustreError: 96100:0:(dcache.c:176:ll_intent_release()) intent 0000000030d9ee48 released [ 4744.389082] LustreError: 96100:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4744.414535] LustreError: 96100:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](0000000041f1d293), intent=open|creat [ 4744.420668] LustreError: 96100:0:(namei.c:1744:ll_create_it()) inode 0000000051ff3669 need_sync_to_mds [0x2000013a1:0x1:0x0] [ 4744.877078] LustreError: 96118:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4744.886868] LustreError: 96118:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 15 previous similar messages [ 4744.900751] LustreError: 96118:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4744.909958] LustreError: 96118:0:(namei.c:956:ll_intent_lock()) Skipped 13 previous similar messages [ 4745.070174] LustreError: 96123:0:(dcache.c:176:ll_intent_release()) intent 00000000813b3685 released [ 4745.077236] LustreError: 96123:0:(dcache.c:176:ll_intent_release()) Skipped 4 previous similar messages [ 4745.100867] Lustre: Remounted lustre-client read-only [ 4745.742782] LustreError: 96158:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 4745.773524] LustreError: 96158:0:(namei.c:1721:ll_create_it()) VFS Op:name=f20.conf-sanity, dir=[0x200000007:0x1:0x0](0000000041f1d293), intent=open|creat [ 4745.789356] LustreError: 96158:0:(namei.c:1744:ll_create_it()) inode 000000007d4524a6 need_sync_to_mds [0x2000013a1:0x2:0x0] [ 4746.272455] LustreError: 96169:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4746.281056] LustreError: 96169:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 21 previous similar messages [ 4746.311959] LustreError: 96169:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 4746.322292] LustreError: 96169:0:(namei.c:956:ll_intent_lock()) Skipped 19 previous similar messages [ 4749.054449] LustreError: 96178:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 4749.065984] LustreError: 96178:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 4749.258731] Lustre: Unmounted lustre-client [ 4785.961214] Lustre: DEBUG MARKER: == conf-sanity test 21a: start mds before ost, stop ost first ========================================================== 13:52:08 (1789408328) [ 4827.569779] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 4838.840854] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 4865.415173] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 4914.203770] Lustre: DEBUG MARKER: == conf-sanity test 21b: start ost before mds, stop mds first ========================================================== 13:54:16 (1789408456) [ 4985.241937] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5023.733934] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5030.657077] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5082.437851] Lustre: DEBUG MARKER: == conf-sanity test 21c: start mds between two osts, stop mds last ========================================================== 13:57:05 (1789408625) [ 5156.608380] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5199.047975] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5206.640452] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5238.700256] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5318.137775] Lustre: DEBUG MARKER: == conf-sanity test 21d: start mgs then ost and then mds ========================================================== 14:01:00 (1789408860) [ 5320.073760] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21d need separate mgs device [ 5322.296854] Lustre: DEBUG MARKER: SKIP: conf-sanity test_21e skipping excluded test 21e [ 5324.776857] Lustre: DEBUG MARKER: == conf-sanity test 22: start a client before osts (should return errs) ========================================================== 14:01:07 (1789408867) [ 5365.384567] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5372.504781] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5395.204191] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5415.805478] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5415.970344] LustreError: 106284:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5415.980178] LustreError: 106284:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 5415.990073] LustreError: 106284:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5415.998731] LustreError: 106284:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 5416.035730] LustreError: 106284:0:(mdc_lib.c:221:mdc_create_pack()) debug1: NOT!!! create_volatile changes to mds_open_volatile [ 5416.085953] LustreError: 106285:0:(dcache.c:176:ll_intent_release()) intent 000000006ae1573e released [ 5416.092036] LustreError: 106285:0:(dcache.c:176:ll_intent_release()) Skipped 7 previous similar messages [ 5416.496379] LustreError: 106295:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5416.500759] LustreError: 106295:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 9 previous similar messages [ 5416.512730] LustreError: 106295:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5416.517818] LustreError: 106295:0:(namei.c:956:ll_intent_lock()) Skipped 7 previous similar messages [ 5418.575689] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5428.878864] Lustre: Unmounted lustre-client [ 5455.305628] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5455.902746] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5472.902198] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ff888c00800.ost_server_uuid 50 [ 5474.464697] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ff888c00800.ost_server_uuid in FULL state after 0 sec [ 5474.722582] LustreError: 107363:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5474.739686] LustreError: 107363:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5474.763777] LustreError: 107363:0:(dcache.c:176:ll_intent_release()) intent 000000006bfdfd6a released [ 5474.786441] LustreError: 107363:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 5474.916310] LustreError: 107363:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000cf816ab2), intent=open|creat [ 5474.921484] LustreError: 107363:0:(namei.c:1744:ll_create_it()) inode 0000000073e8df11 need_sync_to_mds [0x2000032e2:0x1:0x0] [ 5476.819737] LustreError: 107396:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5476.823433] LustreError: 107396:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 13 previous similar messages [ 5476.826467] LustreError: 107396:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5476.831251] LustreError: 107396:0:(namei.c:956:ll_intent_lock()) Skipped 11 previous similar messages [ 5476.945680] Lustre: Unmounted lustre-client [ 5512.425679] Key type lgssc unregistered [ 5512.813102] LNet: 107798:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5512.823544] LNetError: 107798:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5512.847446] LNet: Removed LNI 192.168.201.14@tcp [ 5513.984677] Key type .llcrypt unregistered [ 5513.990136] Key type ._llcrypt unregistered [ 5535.379676] Key type ._llcrypt registered [ 5535.385126] Key type .llcrypt registered [ 5536.794442] Lustre: DEBUG MARKER: == conf-sanity test 23a: interrupt client during recovery mount delay ========================================================== 14:04:39 (1789409079) [ 5539.456809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5539.645817] alg: No test for adler32 (adler32-zlib) [ 5541.167593] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5541.553255] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 5543.256463] Key type lgssc registered [ 5544.790979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5605.660162] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5611.416346] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5637.556310] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5639.925399] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5642.440471] LustreError: 110927:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5642.475847] LustreError: 110927:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5645.321372] Lustre: lustre-MDT0000-mdc-ffff8ff886ca5000: Connection to lustre-MDT0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5647.672410] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 5658.019021] LustreError: MGC192.168.201.114@tcp: Connection to MGS (at 192.168.201.114@tcp) was lost; in progress operations using this service will fail [ 5658.063557] Lustre: Unmounted lustre-client [ 5684.347696] LustreError: lustre-MDT0000-mdc-ffff8ff887086800: operation mds_connect to node 192.168.201.114@tcp failed: rc = -16 [ 5689.404448] LustreError: 111180:0:(lmv_obd.c:1471:lmv_statfs()) lustre-MDT0000-mdc-ffff8ff887086800: can't stat MDS #0: rc = -16 [ 5689.513280] Lustre: Unmounted lustre-client [ 5689.546528] LustreError: 111180:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -16 [ 5716.617511] Key type lgssc unregistered [ 5716.832256] LNet: 111631:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5716.837615] LNetError: 111631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5716.854828] LNet: Removed LNI 192.168.201.14@tcp [ 5717.817737] Key type .llcrypt unregistered [ 5717.818984] Key type ._llcrypt unregistered [ 5741.087974] Key type ._llcrypt registered [ 5741.098255] Key type .llcrypt registered [ 5743.153315] Lustre: DEBUG MARKER: == conf-sanity test 23b: Simulate -EINTR during mount ==== 14:08:05 (1789409285) [ 5746.528958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5746.598415] alg: No test for adler32 (adler32-zlib) [ 5747.796128] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5748.411214] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 5750.287258] Key type lgssc registered [ 5751.417098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5809.371877] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5815.891977] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 5843.100772] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 5849.172786] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 5849.946343] LustreError: 114751:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5849.971877] LustreError: 114751:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 5851.118094] LustreError: 114759:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 5851.139476] LustreError: 114759:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 5851.314084] Lustre: Unmounted lustre-client [ 5889.754850] Key type lgssc unregistered [ 5890.037238] LNet: 115163:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5890.047977] LNetError: 115163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5890.085063] LNet: Removed LNI 192.168.201.14@tcp [ 5890.899209] Key type .llcrypt unregistered [ 5890.912266] Key type ._llcrypt unregistered [ 5908.681305] Key type ._llcrypt registered [ 5908.683431] Key type .llcrypt registered [ 5910.117569] Lustre: DEBUG MARKER: == conf-sanity test 24a: Multiple MDTs on a single node == 14:10:53 (1789409453) [ 5913.195354] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24a mixed loopback and real device not working [ 5915.224736] Lustre: DEBUG MARKER: == conf-sanity test 24b: Multiple MGSs on a single node (should return err) ========================================================== 14:10:57 (1789409457) [ 5918.252702] Lustre: DEBUG MARKER: SKIP: conf-sanity test_24b mixed loopback and real device not working [ 5920.197351] Lustre: DEBUG MARKER: == conf-sanity test 25: Verify modules are referenced ==== 14:11:03 (1789409463) [ 5923.613265] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 5923.627173] alg: No test for adler32 (adler32-zlib) [ 5924.686616] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 5925.014432] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 5926.777477] Key type lgssc registered [ 5927.964913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5989.443719] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 5995.693746] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6021.371415] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6027.317524] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 6027.644922] LustreError: 118915:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6027.689871] LustreError: 118915:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6027.720281] LustreError: 118915:0:(dcache.c:176:ll_intent_release()) intent 000000002b680355 released [ 6027.764899] LustreError: 118915:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 6027.831996] LustreError: 118915:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000923ef64e), intent=open|creat [ 6027.855509] LustreError: 118915:0:(namei.c:1744:ll_create_it()) inode 00000000f44bc459 need_sync_to_mds [0x200005221:0x1:0x0] [ 6028.143850] LustreError: 118921:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6028.160502] LustreError: 118921:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 9 previous similar messages [ 6028.770745] LustreError: 118942:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6028.799312] LustreError: 118942:0:(namei.c:956:ll_intent_lock()) Skipped 10 previous similar messages [ 6031.364825] LustreError: 118950:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6031.367320] LustreError: 118950:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 3 previous similar messages [ 6031.369614] LustreError: 118950:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 6031.598379] Lustre: Unmounted lustre-client [ 6068.696069] Key type lgssc unregistered [ 6069.144773] LNet: 119354:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6069.154490] LNetError: 119354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6069.169824] LNet: Removed LNI 192.168.201.14@tcp [ 6070.373653] Key type .llcrypt unregistered [ 6070.376079] Key type ._llcrypt unregistered [ 6092.913497] Key type ._llcrypt registered [ 6092.914500] Key type .llcrypt registered [ 6094.272456] Lustre: DEBUG MARKER: == conf-sanity test 26: MDT startup failure cleans LOV (should return errs) ========================================================== 14:13:57 (1789409637) [ 6095.781638] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6095.811612] alg: No test for adler32 (adler32-zlib) [ 6097.268793] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 6097.657274] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 6099.512110] Key type lgssc registered [ 6102.412972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6181.753252] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6189.324185] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6213.555820] Key type lgssc unregistered [ 6214.141092] LNet: 122441:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6214.164490] LNetError: 122441:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6214.195200] LNet: Removed LNI 192.168.201.14@tcp [ 6215.456327] Key type .llcrypt unregistered [ 6215.467943] Key type ._llcrypt unregistered [ 6240.981842] Key type ._llcrypt registered [ 6240.983524] Key type .llcrypt registered [ 6242.362902] Lustre: DEBUG MARKER: == conf-sanity test 27a: Reacquire MGS lock if OST started first ========================================================== 14:16:25 (1789409785) [ 6253.735276] Key type .llcrypt unregistered [ 6253.737136] Key type ._llcrypt unregistered [ 6265.598225] Key type ._llcrypt registered [ 6265.600947] Key type .llcrypt registered [ 6266.963709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6267.010581] alg: No test for adler32 (adler32-zlib) [ 6268.386901] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 6268.944565] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 6270.911870] Key type lgssc registered [ 6273.220159] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6370.811301] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6414.629300] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6424.136226] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6471.760280] Key type lgssc unregistered [ 6472.400085] LNet: 126315:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6472.437701] LNetError: 126315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6472.472042] LNet: Removed LNI 192.168.201.14@tcp [ 6474.237241] Key type .llcrypt unregistered [ 6474.243939] Key type ._llcrypt unregistered [ 6497.558490] Key type ._llcrypt registered [ 6497.564732] Key type .llcrypt registered [ 6499.553816] Lustre: DEBUG MARKER: == conf-sanity test 27b: Reacquire MGS lock after failover ========================================================== 14:20:41 (1789410041) [ 6503.339332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6503.356606] alg: No test for adler32 (adler32-zlib) [ 6504.574129] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 6505.015487] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 6506.743756] Key type lgssc registered [ 6509.678627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6568.728848] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6575.491138] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6602.908847] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6610.029122] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 6615.029767] Lustre: lustre-MDT0000-mdc-ffff8ff884a54000: Connection to lustre-MDT0000 (at 192.168.201.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6631.135201] Lustre: 127289:0:(client.c:2504:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1789410159/real 1789410159] req@00000000e255ccde x1876332431543680/t0(0) o400->MGC192.168.201.114@tcp@192.168.201.114@tcp:26/25 lens 224/224 e 0 to 1 dl 1789410175 ref 1 fl Rpc:XNQr/200/ffffffff rc 0/-1 job:'' uid:0 gid:0 projid:4294967295 [ 6631.161251] LustreError: MGC192.168.201.114@tcp: Connection to MGS (at 192.168.201.114@tcp) was lost; in progress operations using this service will fail [ 6641.395378] Lustre: Evicted from MGS (at 192.168.201.114@tcp) after server handle changed from 0x7b03539420f93c7 to 0x7b03539420f9502 [ 6641.404867] Lustre: MGC192.168.201.114@tcp: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 6649.780658] Lustre: lustre-MDT0000-mdc-ffff8ff884a54000: Connection restored to 192.168.201.114@tcp (at 192.168.201.114@tcp) [ 6664.129626] LustreError: 129842:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6664.205900] LustreError: 129842:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6664.230845] LustreError: 129842:0:(dcache.c:176:ll_intent_release()) intent 00000000dc85b014 released [ 6664.244706] LustreError: 129842:0:(mdc_lib.c:329:mdc_open_pack()) debug: NOT!!! create_volatile changes to mds_open_volatile [ 6664.294623] LustreError: 129842:0:(namei.c:1721:ll_create_it()) VFS Op:name=a, dir=[0x200000007:0x1:0x0](00000000a87f1b1c), intent=open|creat [ 6664.304307] LustreError: 129842:0:(namei.c:1744:ll_create_it()) inode 000000003b3f5be8 need_sync_to_mds [0x200007161:0x1:0x0] [ 6664.710866] LustreError: 129865:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6664.718909] LustreError: 129865:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 12 previous similar messages [ 6664.731703] LustreError: 129865:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6664.735860] LustreError: 129865:0:(namei.c:956:ll_intent_lock()) Skipped 10 previous similar messages [ 6666.336844] LustreError: 129874:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6666.356856] LustreError: 129874:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 6666.505787] Lustre: Unmounted lustre-client [ 6693.380281] Key type lgssc unregistered [ 6693.595800] LNet: 130276:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6693.603576] LNetError: 130276:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6693.640291] LNet: Removed LNI 192.168.201.14@tcp [ 6694.968826] Key type .llcrypt unregistered [ 6694.979070] Key type ._llcrypt unregistered [ 6721.611956] Key type ._llcrypt registered [ 6721.617462] Key type .llcrypt registered [ 6723.086261] Lustre: DEBUG MARKER: == conf-sanity test 28A: permanent parameter setting ===== 14:24:25 (1789410265) [ 6727.053670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6727.085659] alg: No test for adler32 (adler32-zlib) [ 6728.303831] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 6728.878037] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 6730.727268] Key type lgssc registered [ 6731.952542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6788.056239] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6795.367251] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 6818.037938] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 6825.029587] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 6843.687128] LustreError: 133557:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6843.699401] LustreError: 133557:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6845.298939] LustreError: 133565:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6845.309131] LustreError: 133565:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 6845.405350] Lustre: Unmounted lustre-client [ 6845.766785] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 6855.880084] LustreError: 134083:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6855.890280] LustreError: 134083:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 6855.928697] LustreError: 134083:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 6855.940060] LustreError: 134083:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 6858.285855] LustreError: 134091:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 6858.297937] LustreError: 134091:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 6858.422427] Lustre: Unmounted lustre-client [ 6891.587596] Key type lgssc unregistered [ 6892.260431] LNet: 134495:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6892.269554] LNetError: 134495:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6892.293287] LNet: Removed LNI 192.168.201.14@tcp [ 6893.477150] Key type .llcrypt unregistered [ 6893.478509] Key type ._llcrypt unregistered [ 6909.593047] Key type ._llcrypt registered [ 6909.595572] Key type .llcrypt registered [ 6911.060451] Lustre: DEBUG MARKER: == conf-sanity test 28a: set symlink parameters permanently with lctl ========================================================== 14:27:33 (1789410453) [ 6914.545052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 6914.563152] alg: No test for adler32 (adler32-zlib) [ 6915.590526] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 6915.797335] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 6917.463205] Key type lgssc registered [ 6918.735546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6979.730622] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 6986.669734] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7010.544523] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7013.578668] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7053.126218] LustreError: 137456:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7053.138679] LustreError: 137456:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7054.875651] LustreError: 137465:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7054.887363] LustreError: 137465:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7054.990463] Lustre: Unmounted lustre-client [ 7089.818643] Key type lgssc unregistered [ 7090.065923] LNet: 137869:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7090.071478] LNetError: 137869:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7090.090334] LNet: Removed LNI 192.168.201.14@tcp [ 7091.124816] Key type .llcrypt unregistered [ 7091.137317] Key type ._llcrypt unregistered [ 7110.943653] Key type ._llcrypt registered [ 7110.946434] Key type .llcrypt registered [ 7112.671835] Lustre: DEBUG MARKER: == conf-sanity test 28b: verify client-side parameters are set upon mount ========================================================== 14:30:55 (1789410655) [ 7115.793955] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7115.810071] alg: No test for adler32 (adler32-zlib) [ 7116.846865] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 7117.092505] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 7118.760112] Key type lgssc registered [ 7121.017915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7192.924283] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7200.694871] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7225.486337] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7227.840706] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7228.718595] LustreError: 141012:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7228.762891] LustreError: 141012:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7230.680550] LustreError: 141020:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7230.686621] LustreError: 141020:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7230.792286] Lustre: Unmounted lustre-client [ 7231.329953] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7237.056840] LustreError: 141501:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7237.061132] LustreError: 141501:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 7237.070503] LustreError: 141501:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7237.074804] LustreError: 141501:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 7239.662194] LustreError: 141509:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7239.676792] LustreError: 141509:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7239.827936] Lustre: Unmounted lustre-client [ 7279.956676] Key type lgssc unregistered [ 7280.181928] LNet: 141913:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7280.193654] LNetError: 141913:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7280.213247] LNet: Removed LNI 192.168.201.14@tcp [ 7280.867926] Key type .llcrypt unregistered [ 7280.872687] Key type ._llcrypt unregistered [ 7291.887660] Key type ._llcrypt registered [ 7291.889284] Key type .llcrypt registered [ 7293.669734] Lustre: DEBUG MARKER: == conf-sanity test 28c: verify filesystem parameters are set upon mount ========================================================== 14:33:56 (1789410836) [ 7297.634985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7297.676379] alg: No test for adler32 (adler32-zlib) [ 7298.864493] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 7299.246557] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 7300.927355] Key type lgssc registered [ 7302.678911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7369.391237] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7377.236527] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7404.638907] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7407.802413] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7408.512805] LustreError: 144636:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7408.546964] LustreError: 144636:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7410.892462] LustreError: 144644:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7410.901508] LustreError: 144644:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7411.158540] Lustre: Unmounted lustre-client [ 7411.681513] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7417.918472] LustreError: 145130:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7417.927382] LustreError: 145130:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 7417.940912] LustreError: 145130:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7417.946442] LustreError: 145130:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 7420.490232] LustreError: 145138:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7420.535383] LustreError: 145138:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7420.776270] Lustre: Unmounted lustre-client [ 7450.928305] Key type lgssc unregistered [ 7451.371395] LNet: 145543:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7451.381761] LNetError: 145543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7451.420396] LNet: Removed LNI 192.168.201.14@tcp [ 7452.708517] Key type .llcrypt unregistered [ 7452.717493] Key type ._llcrypt unregistered [ 7464.572994] Key type ._llcrypt registered [ 7464.581455] Key type .llcrypt registered [ 7466.161723] Lustre: DEBUG MARKER: == conf-sanity test 29: permanently remove an OST ======== 14:36:48 (1789411008) [ 7469.629415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7469.651240] alg: No test for adler32 (adler32-zlib) [ 7470.963367] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 7471.350291] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 7473.128675] Key type lgssc registered [ 7474.460598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7528.554992] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7535.222797] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7559.395775] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7562.284752] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7591.533704] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7593.947370] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 0 sec [ 7616.024483] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7620.478363] LustreError: 148998:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7620.520787] LustreError: 148998:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7621.721509] LustreError: 149006:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7621.724789] LustreError: 149006:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7621.794150] Lustre: Unmounted lustre-client [ 7622.141297] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 7622.343658] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7631.188101] LustreError: 149134:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7631.203805] LustreError: 149134:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 7631.234983] LustreError: 149134:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 7631.247965] LustreError: 149134:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 7633.419520] LustreError: 149142:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7633.426846] LustreError: 149142:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 7633.561960] Lustre: Unmounted lustre-client [ 7671.006792] Key type lgssc unregistered [ 7671.549753] LNet: 149590:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7671.560894] LNetError: 149590:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7671.586418] LNet: Removed LNI 192.168.201.14@tcp [ 7672.484522] Key type .llcrypt unregistered [ 7672.486504] Key type ._llcrypt unregistered [ 7717.392580] Key type ._llcrypt registered [ 7717.394254] Key type .llcrypt registered [ 7718.925080] Lustre: DEBUG MARKER: == conf-sanity test 30a: Big config llog and permanent parameter deletion ========================================================== 14:41:01 (1789411261) [ 7722.492079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 7722.574134] alg: No test for adler32 (adler32-zlib) [ 7723.701282] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 7723.979344] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 7725.687509] Key type lgssc registered [ 7727.002390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7789.559372] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 7798.239453] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 7825.909149] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 7829.022784] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 7998.346166] LustreError: 154299:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 7998.359911] LustreError: 154299:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8000.109924] LustreError: 154307:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8000.116330] LustreError: 154307:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8000.242166] Lustre: Unmounted lustre-client [ 8000.793316] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8002.799919] LustreError: 154372:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8002.807255] LustreError: 154372:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 8002.839244] LustreError: 154372:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8002.852851] LustreError: 154372:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 8004.865592] LustreError: 154381:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8004.895931] LustreError: 154381:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8005.004487] Lustre: Unmounted lustre-client [ 8005.549976] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8008.563245] Lustre: Unmounted lustre-client [ 8039.629705] Key type lgssc unregistered [ 8040.045756] LNet: 154851:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8040.064294] LNetError: 154851:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8040.091379] LNet: Removed LNI 192.168.201.14@tcp [ 8040.942921] Key type .llcrypt unregistered [ 8040.945864] Key type ._llcrypt unregistered [ 8060.462417] Key type ._llcrypt registered [ 8060.464173] Key type .llcrypt registered [ 8061.589847] Lustre: DEBUG MARKER: == conf-sanity test 30b: Remove failover nids ============ 14:46:44 (1789411604) [ 8064.804872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8064.904645] alg: No test for adler32 (adler32-zlib) [ 8066.116796] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 8066.462459] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 8068.175303] Key type lgssc registered [ 8069.922331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8134.964244] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid 1475 0 [ 8144.453563] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid 1475 0 [ 8172.084400] Lustre: DEBUG MARKER: oleg114-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid 1475 0 [ 8175.027943] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8180.423286] LustreError: 158060:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8180.457297] LustreError: 158060:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8182.264358] LustreError: 158068:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8182.274594] LustreError: 158068:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 1 [ 8182.358418] Lustre: Unmounted lustre-client [ 8182.806580] Lustre: Mounted lustre-client - version 2.17.58_39_gb3cb314 [ 8183.562741] LustreError: 158130:0:(llite_lib.c:4173:ll_prep_md_op_data()) debug: not match filename_is_volatile or other error [NULL] [ 8183.569416] LustreError: 158130:0:(llite_lib.c:4173:ll_prep_md_op_data()) Skipped 1 previous similar message [ 8183.584611] LustreError: 158130:0:(namei.c:956:ll_intent_lock()) intent lock 8 on i1 [0x200000007:0x1:0x0] suppgids 0 0: rc 0 [ 8183.601117] LustreError: 158130:0:(namei.c:956:ll_intent_lock()) Skipped 1 previous similar message [ 8185.579113] Lustre: Unmounted lustre-client [ 8214.371422] Key type lgssc unregistered [ 8214.627637] LNet: 158541:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8214.643044] LNetError: 158541:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8214.665827] LNet: Removed LNI 192.168.201.14@tcp [ 8215.446118] Key type .llcrypt unregistered [ 8215.450255] Key type ._llcrypt unregistered [ 8240.144187] Key type ._llcrypt registered [ 8240.147714] Key type .llcrypt registered [ 8241.430588] Lustre: DEBUG MARKER: == conf-sanity test 31: Connect to non-existent node (shouldn't crash) ========================================================== 14:49:44 (1789411784) [ 8243.408400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 8243.470290] alg: No test for adler32 (adler32-zlib) [ 8244.736811] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 8245.066424] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 8246.783242] Key type lgssc registered [ 8248.317139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8272.992860] LustreError: 160233:0:(mgc_request.c:233:do_config_log_add()) MGC4.3.2.1@tcp: failed processing log, type 1: rc = -110 [ 8281.567952] LustreError: 160242:0:(mgc_request.c:614:do_requeue()) failed processing log: -110 [ 8323.937286] LNet: 1 local NIs in recovery (showing 1): 192.168.201.14@tcp [ 8398.687350] LNet: 1 local NIs in recovery (showing 1): 192.168.201.14@tcp [ 8429.536988] LustreError: MGC4.3.2.1@tcp: Confguration from log lustre-client failed from MGS -110. Communication error between node & MGS, a bad configuration, or other errors. See syslog for more info [ 8429.560623] Lustre: Unmounted lustre-client [ 8429.576063] LustreError: 160233:0:(super25.c:179:lustre_fill_super()) llite: Unable to mount : rc = -110 [ 8444.893255] Key type lgssc unregistered [ 8452.449376] LNet: 160649:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8452.455618] LNetError: 160649:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8529.258096] LNet: Removed LNI 192.168.201.14@tcp [ 8530.286263] Key type .llcrypt unregistered [ 8530.292032] Key type ._llcrypt unregistered [ 8573.588426] Key type ._llcrypt registered [ 8573.589755] Key type .llcrypt registered [ 8574.150642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8574.163088] alg: No test for adler32 (adler32-zlib) [ 8575.284891] Lustre: Lustre: Build Version: 2.17.58_39_gb3cb314 [ 8575.502876] LNet: Added LNI 192.168.201.14@tcp [8/256/0/180] [ 8577.304070] Key type lgssc registered [ 8579.149309] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8630.160680] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 8331 sec ========== 14:56:12 (1789412172) [ 8631.763290] Lustre: DEBUG MARKER: === conf-sanity: start cleanup 14:56:14 (1789412174) === [ 8635.159214] Lustre: DEBUG MARKER: === conf-sanity: finish cleanup 14:56:18 (1789412178) === [ 8676.231544] Key type lgssc unregistered [ 8676.457596] LNet: 164886:0:(lib-ptl.c:964:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8676.460671] LNetError: 164886:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8676.473925] LNet: Removed LNI 192.168.201.14@tcp [ 8677.139200] Key type .llcrypt unregistered [ 8677.141057] Key type ._llcrypt unregistered