[ 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 427417984 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2464 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE2300 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 0022C0 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2374 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE2404 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE243C 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe2300-0xbffe2373] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22ff] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2374-0xbffe2403] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe2404-0xbffe243b] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe243c-0xbffe2463] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003088] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.007320] ..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.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010141] LSM: Security Framework initializing [ 0.011052] Yama: becoming mindful. [ 0.012032] SELinux: Initializing. [ 0.013069] *** VALIDATE selinux *** [ 0.021644] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026318] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027149] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028127] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029117] *** VALIDATE tmpfs *** [ 0.031092] *** VALIDATE proc *** [ 0.032251] *** VALIDATE cgroup *** [ 0.033008] *** VALIDATE cgroup2 *** [ 0.034296] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035187] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037030] Spectre V2 : User space: Vulnerable [ 0.038009] Speculative Store Bypass: Vulnerable [ 0.041366] debug: unmapping init [mem 0xffffffff98059000-0xffffffff98060fff] [ 0.044000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044714] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045024] ... version: 2 [ 0.046012] ... bit width: 48 [ 0.047012] ... generic registers: 4 [ 0.048012] ... value mask: 0000ffffffffffff [ 0.049012] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.053209] rcu: Hierarchical SRCU implementation. [ 0.055449] smp: Bringing up secondary CPUs ... [ 0.056579] x86: Booting SMP configuration: [ 0.057026] .... node #0, CPUs: #1 #2 #3 [ 0.062092] smp: Brought up 1 node, 4 CPUs [ 0.064015] smpboot: Max logical packages: 1 [ 0.065012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.237019] node 0 deferred pages initialised in 170ms [ 0.241158] devtmpfs: initialized [ 0.242221] x86/mm: Memory block size: 128MB [ 0.244262] gcov: version magic: 0x41383552 [ 0.246278] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.247080] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.248243] pinctrl core: initialized pinctrl subsystem [ 0.249196] [ 0.249749] ************************************************************* [ 0.250021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.251022] ** ** [ 0.252023] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.253019] ** ** [ 0.254019] ** This means that this kernel is built to expose internal ** [ 0.255020] ** IOMMU data structures, which may compromise security on ** [ 0.256016] ** your system. ** [ 0.257017] ** ** [ 0.258016] ** If you see this message and you are not debugging the ** [ 0.259013] ** kernel, report this immediately to your vendor! ** [ 0.260025] ** ** [ 0.261012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.262015] ************************************************************* [ 0.263716] NET: Registered protocol family 16 [ 0.264405] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.265116] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.266059] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.267641] cpuidle: using governor menu [ 0.270765] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.273547] PCI: Using configuration type 1 for base access [ 0.276123] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.286126] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.289050] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.293195] cryptd: max_cpu_qlen set to 1000 [ 0.295234] ACPI: Added _OSI(Module Device) [ 0.297021] ACPI: Added _OSI(Processor Device) [ 0.299019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.301025] ACPI: Added _OSI(Processor Aggregator Device) [ 0.306490] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.312346] ACPI: Interpreter enabled [ 0.313046] ACPI: PM: (supports S0 S3 S4 S5) [ 0.314017] ACPI: Using IOAPIC for interrupt routing [ 0.316203] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.322539] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.332000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.335067] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.337023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.341106] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.346657] acpiphp: Slot [2] registered [ 0.348174] acpiphp: Slot [5] registered [ 0.350170] acpiphp: Slot [6] registered [ 0.352134] acpiphp: Slot [3] registered [ 0.353446] acpiphp: Slot [4] registered [ 0.355122] acpiphp: Slot [7] registered [ 0.357121] acpiphp: Slot [8] registered [ 0.358133] acpiphp: Slot [9] registered [ 0.360119] acpiphp: Slot [10] registered [ 0.361109] acpiphp: Slot [11] registered [ 0.363112] acpiphp: Slot [12] registered [ 0.364118] acpiphp: Slot [13] registered [ 0.366140] acpiphp: Slot [14] registered [ 0.368134] acpiphp: Slot [15] registered [ 0.370126] acpiphp: Slot [16] registered [ 0.371167] acpiphp: Slot [17] registered [ 0.373144] acpiphp: Slot [18] registered [ 0.374132] acpiphp: Slot [19] registered [ 0.376142] acpiphp: Slot [20] registered [ 0.378206] acpiphp: Slot [21] registered [ 0.380278] acpiphp: Slot [22] registered [ 0.382290] acpiphp: Slot [23] registered [ 0.384281] acpiphp: Slot [24] registered [ 0.386121] acpiphp: Slot [25] registered [ 0.387146] acpiphp: Slot [26] registered [ 0.389187] acpiphp: Slot [27] registered [ 0.392159] acpiphp: Slot [28] registered [ 0.394142] acpiphp: Slot [29] registered [ 0.396165] acpiphp: Slot [30] registered [ 0.398126] acpiphp: Slot [31] registered [ 0.400182] PCI host bridge to bus 0000:00 [ 0.401026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.404034] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.407032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.410044] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.413052] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.416037] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.418207] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.421192] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.425575] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.432994] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.437060] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.442038] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.445019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.448030] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.452404] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.455811] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.458054] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.460872] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.464928] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.473984] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.478015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.483011] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.491764] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.495649] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.508016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.517675] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.523016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.529020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.551020] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.561000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.563385] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.565432] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.568383] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.570288] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.574159] iommu: Default domain type: Passthrough [ 0.577484] SCSI subsystem initialized [ 0.579129] ACPI: bus type USB registered [ 0.580169] usbcore: registered new interface driver usbfs [ 0.582094] usbcore: registered new interface driver hub [ 0.583084] usbcore: registered new device driver usb [ 0.585198] pps_core: LinuxPPS API ver. 1 registered [ 0.586008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.589070] PTP clock support registered [ 0.591094] EDAC MC: Ver: 3.0.0 [ 0.593142] PCI: Using ACPI for IRQ routing [ 0.595747] NetLabel: Initializing [ 0.596010] NetLabel: domain hash size = 128 [ 0.597014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.599111] NetLabel: unlabeled traffic allowed by default [ 0.602221] vgaarb: loaded [ 0.604351] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.609028] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.617932] clocksource: Switched to clocksource kvm-clock [ 0.741295] VFS: Disk quotas dquot_6.6.0 [ 0.742771] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.744784] *** VALIDATE ramfs *** [ 0.745770] *** VALIDATE hugetlbfs *** [ 0.747021] pnp: PnP ACPI init [ 0.749859] pnp: PnP ACPI: found 6 devices [ 0.778504] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.781492] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.784075] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.787247] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.790444] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.793697] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.798262] NET: Registered protocol family 2 [ 0.801731] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.807366] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.810925] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.816224] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.819544] TCP: Hash tables configured (established 65536 bind 65536) [ 0.822448] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.825265] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.827982] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.831110] NET: Registered protocol family 1 [ 0.833845] RPC: Registered named UNIX socket transport module. [ 0.835972] RPC: Registered udp transport module. [ 0.837697] RPC: Registered tcp transport module. [ 0.839475] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.841587] NET: Registered protocol family 44 [ 0.843216] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.845449] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.847674] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.849992] PCI: CLS 0 bytes, default 64 [ 0.851577] Unpacking initramfs... [ 2.290175] debug: unmapping init [mem 0xffff92fefcc64000-0xffff92fefffcffff] [ 2.294458] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.296740] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.299647] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.817573] Initialise system trusted keyrings [ 2.819744] Key type blacklist registered [ 2.824192] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.835301] zbud: loaded [ 2.838383] *** VALIDATE nfs *** [ 2.840272] *** VALIDATE nfs4 *** [ 2.843289] pstore: using deflate compression [ 2.848837] Platform Keyring initialized [ 2.984836] NET: Registered protocol family 38 [ 2.987246] Key type asymmetric registered [ 2.988975] Asymmetric key parser 'x509' registered [ 2.991589] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.995280] io scheduler mq-deadline registered [ 2.996549] io scheduler kyber registered [ 2.998096] io scheduler bfq registered [ 3.000588] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.004265] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.007360] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.010441] ACPI: Power Button [PWRF] [ 3.016771] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.023383] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.039317] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.070203] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.097770] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.103285] Non-volatile memory driver v1.3 [ 3.105340] Linux agpgart interface v0.103 [ 3.138331] virtio_blk virtio1: [vda] 135296 512-byte logical blocks (69.3 MB/66.1 MiB) [ 3.143206] vda: detected capacity change from 0 to 69271552 [ 3.158477] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.161614] vdb: detected capacity change from 0 to 1073741824 [ 3.170862] libphy: Fixed MDIO Bus: probed [ 3.176584] usbcore: registered new interface driver usbserial_generic [ 3.179624] usbserial: USB Serial support registered for generic [ 3.182453] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.187375] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.189485] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.192288] mousedev: PS/2 mouse device common for all mice [ 3.195232] rtc_cmos 00:05: RTC can wake from S4 [ 3.197269] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.198123] rtc_cmos 00:05: registered as rtc0 [ 3.206288] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.210387] intel_pstate: CPU model not supported [ 3.213499] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.218414] hid: raw HID events driver (C) Jiri Kosina [ 3.220862] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.221334] usbcore: registered new interface driver usbhid [ 3.227099] usbhid: USB HID core driver [ 3.228743] drop_monitor: Initializing network drop monitor service [ 3.231593] Initializing XFRM netlink socket [ 3.233864] NET: Registered protocol family 10 [ 3.236933] Segment Routing with IPv6 [ 3.238320] NET: Registered protocol family 17 [ 3.240517] mpls_gso: MPLS GSO support [ 3.247400] RAS: Correctable Errors collector initialized. [ 3.249721] AVX version of gcm_enc/dec engaged. [ 3.251445] AES CTR mode by8 optimization enabled [ 3.347089] sched_clock: Marking stable (3347066358, 0)->(4244209677, -897143319) [ 3.351219] registered taskstats version 1 [ 3.355773] Loading compiled-in X.509 certificates [ 3.358186] zswap: loaded using pool lzo/zbud [ 3.382853] Key type big_key registered [ 3.396595] Key type encrypted registered [ 3.398464] ima: No TPM chip found, activating TPM-bypass! [ 3.400987] ima: Allocated hash algorithm: sha1 [ 3.402967] ima: No architecture policies found [ 3.405073] evm: Initialising EVM extended attributes: [ 3.406955] evm: security.selinux [ 3.408314] evm: security.ima [ 3.409423] evm: security.capability [ 3.410719] evm: HMAC attrs: 0x1 [ 3.413234] rtc_cmos 00:05: setting system clock to 2026-05-28 13:24:08 UTC (1779974648) [ 3.421195] debug: unmapping init [mem 0xffffffff99003000-0xffffffff991fffff] [ 3.425298] debug: unmapping init [mem 0xffffffff97d82000-0xffffffff98058fff] [ 3.437193] Write protecting the kernel read-only data: 28672k [ 3.441108] debug: unmapping init [mem 0xffffffff96403000-0xffffffff965fffff] [ 3.444279] debug: unmapping init [mem 0xffffffff96d14000-0xffffffff96dfffff] [ 3.489783] 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.497925] systemd[1]: Detected virtualization kvm. [ 3.499753] systemd[1]: Detected architecture x86-64. [ 3.501434] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.531228] systemd[1]: No hostname configured. [ 3.532869] systemd[1]: Set hostname to . [ 3.535129] random: systemd: uninitialized urandom read (16 bytes read) [ 3.537617] systemd[1]: Initializing machine ID from random generator. [ 3.580362] random: ln: uninitialized urandom read (6 bytes read) [ 3.681217] random: systemd: uninitialized urandom read (16 bytes read) [ 3.684156] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.687868] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.691738] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.353288] device-mapper: uevent: version 1.0.3 [ 4.355592] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ 5.167629] random: fast init done [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.316372] virtio_net virtio0 ens2: renamed from eth0 [ 5.355276] scsi host0: ata_piix [ 5.403650] scsi host1: ata_piix [ 5.405718] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.408949] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.251635] dracut-initqueue[584]: RTNETLINK answers: File exists [ 9.921226] random: crng init done [ 9.922742] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.552971] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ 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 Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.862191] printk: systemd: 24 output lines suppressed due to ratelimiting [ 12.107139] SELinux: Disabled at runtime. [ 12.167103] 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) [ 12.177949] systemd[1]: Detected virtualization kvm. [ 12.179779] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.745702] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.749174] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.753343] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.758231] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.762163] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.769651] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.774384] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ 12.868345] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 13.347037] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.775970] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.800286] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.899898] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.916515] EDAC sbridge: Ver: 1.1.2 [ 15.122572] Key type dns_resolver registered [ 15.481539] NFS: Registering the id_resolver key type [ 15.484757] Key type id_resolver registered [ 15.486727] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Mark the need to relabel after reboot. 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 daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Authorization Manager. [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg651-client login: [ 59.042896] libcfs: loading out-of-tree module taints kernel. [ 59.353383] Key type ._llcrypt registered [ 59.365719] Key type .llcrypt registered [ 59.770294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 59.788518] alg: No test for adler32 (adler32-zlib) [ 61.232995] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [ 62.062970] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [ 63.848600] Key type lgssc registered [ 65.538271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 233.171548] Lustre: Mounted lustre-client [ 238.171488] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.117123] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing check_logdir /tmp/testlogs/ [ 259.050200] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 24s idle [ 259.294100] hrtimer: interrupt took 5786495 ns [ 261.981623] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing yml_node [ 266.723323] Lustre: DEBUG MARKER: Client: 2.17.50.143 [ 268.761864] Lustre: DEBUG MARKER: MDS: 2.17.50.143 [ 271.037984] Lustre: DEBUG MARKER: OSS: 2.17.50.143 [ 272.666759] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu May 28 09:28:36 EDT 2026 [ 291.212316] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 292.574105] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 293.761780] Lustre: DEBUG MARKER: === sanityn: start setup 09:28:57 (1779974937) === [ 294.253513] Lustre: Mounted lustre-client [ 297.059854] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing check_config_client /mnt/lustre [ 314.772043] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 325.949476] Lustre: DEBUG MARKER: === sanityn: finish setup 09:29:29 (1779974969) === [ 328.010449] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 09:29:32 (1779974972) [ 334.736098] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 09:29:38 (1779974978) [ 340.773882] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 09:29:44 (1779974984) [ 347.281928] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 09:29:50 (1779974990) [ 354.981412] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 09:29:58 (1779974998) [ 361.956391] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 09:30:05 (1779975005) [ 368.336538] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 09:30:12 (1779975012) [ 374.236268] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 09:30:17 (1779975017) [ 381.213454] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 09:30:24 (1779975024) [ 390.259329] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 09:30:33 (1779975033) [ 398.017342] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 09:30:41 (1779975041) [ 401.891474] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 20s idle [ 401.899450] Lustre: Skipped 1 previous similar message [ 404.959230] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 09:30:48 (1779975048) [ 410.878140] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 09:30:54 (1779975054) [ 416.921633] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 09:31:00 (1779975060) [ 422.369934] Lustre: lustre-OST0001-osc-ffff92ff592bb800: disconnect after 23s idle [ 423.391458] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 09:31:07 (1779975067) [ 429.428471] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 09:31:13 (1779975073) [ 436.138416] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 09:31:19 (1779975079) [ 443.036186] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 09:31:26 (1779975086) [ 453.256315] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 09:31:36 (1779975096) [ 460.522850] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 09:31:44 (1779975104) [ 469.053589] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 09:31:51 (1779975111) [ 469.644650] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 609.413471] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 09:34:13 (1779975253) [ 617.940175] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 09:34:21 (1779975261) [ 625.692205] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 09:34:29 (1779975269) [ 632.887905] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 09:34:36 (1779975276) [ 639.935770] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 09:34:43 (1779975283) [ 646.873893] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 09:34:50 (1779975290) [ 648.891088] Lustre: DEBUG MARKER: chmod [ 655.189620] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 09:34:59 (1779975299) [ 1658.988725] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 09:51:42 (1779976302) [ 1815.008281] Lustre: lustre-OST0001-osc-ffff92ff592bb800: disconnect after 21s idle [ 1890.212435] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 09:55:33 (1779976533) [ 2001.244683] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 09:57:24 (1779976644) [ 2142.582259] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 09:59:46 (1779976786) [ 2178.532710] Lustre: lustre-OST0001-osc-ffff92ff592bb800: disconnect after 21s idle [ 2179.490054] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:00:23 (1779976823) [ 2186.203985] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:00:30 (1779976830) [ 2187.068060] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.155424] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.282315] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.359770] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.427418] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.507700] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.594993] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.661418] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.778503] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.901271] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2187.968716] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.031894] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.111610] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.229781] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.307728] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.381431] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.480881] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.572987] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.654748] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.736763] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.819248] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.912696] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2188.981566] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.058830] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.121757] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.192705] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.272324] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.342495] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.409852] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.474889] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.547853] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.611646] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.701667] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.789545] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.872694] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2189.947616] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.013697] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.070239] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.128088] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.201995] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.300553] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.361787] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.430310] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.490565] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.570996] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.648570] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.714235] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.806835] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.884263] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2190.970946] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.050184] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.132414] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.206363] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.280407] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.354248] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.403977] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.511383] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.588249] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.660953] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.728453] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.790216] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.863716] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2191.953877] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.031733] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.091196] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.132987] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.179381] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.239614] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.316200] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.393333] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.492311] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.552265] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.640442] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.719139] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.803128] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.871462] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2192.943432] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.011451] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.113244] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.201071] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.284317] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.383307] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.454794] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.535984] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.599172] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.664714] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.729742] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.810731] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.884734] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2193.964991] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.040167] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.129975] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.221862] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.307949] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.375566] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.439848] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.538735] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.625309] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.697764] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.783867] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.855072] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.928117] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2194.996509] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.068540] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.131802] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.230531] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.296617] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.365270] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.429626] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.508730] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.573482] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.624713] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.717936] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.792423] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.860920] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.920302] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2195.990658] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.088305] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.156630] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.258641] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.356625] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.464212] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.564519] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.669176] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.763787] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.825953] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.881268] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2196.959981] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.034827] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.116453] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.168400] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.248392] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.332119] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.428532] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.530614] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.637893] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.725100] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.787364] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.852636] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2197.918837] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.014582] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.092798] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.158749] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.211505] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.261976] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.317192] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.380199] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.463965] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.524856] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.592040] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.646878] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.717662] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.781538] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.849848] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.890251] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2198.946723] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.023112] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.094286] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.178502] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.240582] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.304765] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.378119] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.444934] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.519777] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.612974] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.677887] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.753711] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.825326] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.926705] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2199.993374] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.061899] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.152251] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.244877] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.311442] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.382987] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.454762] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.531837] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.681407] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.771950] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.843741] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.903563] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2200.985587] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.040347] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.091543] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.171432] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.237655] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.299358] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.382761] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.455321] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.531824] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.591453] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.651805] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.710388] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.771113] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.833022] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.899390] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2201.972496] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.053303] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.146626] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.219894] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.295904] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.352516] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.403799] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.470911] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.559104] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.634401] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.683049] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.743207] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.811916] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.894895] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2202.959281] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.037075] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.095547] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.166388] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.240866] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.307972] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.349765] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.414803] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.469370] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.522488] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.562384] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.624709] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.697564] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.778502] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.858610] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.926450] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2203.989742] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.051905] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.116935] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.128926] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 21s idle [ 2204.203879] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.300349] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.380148] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.464487] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.531293] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.604372] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.677101] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.742031] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.802777] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.873179] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2204.958106] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.036808] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.128704] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.199313] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.290674] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.361224] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.431816] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.498276] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.565142] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.632637] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.713982] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.808187] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2205.967581] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2206.127126] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2206.255398] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2206.352551] rw_seq_cst_vs_d (32407): drop_caches: 3 [ 2213.908964] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:00:57 (1779976857) [ 2214.350365] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.505634] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.630328] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.714436] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.840688] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.896928] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2214.964992] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.043360] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.109515] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.231709] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.286058] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.320786] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.369250] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.496093] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.678923] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.781536] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.828025] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2215.924915] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.086546] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.148173] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.300104] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.377807] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.432026] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2216.845289] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2217.021501] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2217.242859] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2217.510661] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2217.601489] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2217.814497] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.029874] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.079338] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.143198] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.221752] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.375765] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.488781] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.634654] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.751964] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2218.901390] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.083756] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.194549] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.248708] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.445296] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.515518] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2219.756452] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.024732] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.093405] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.288815] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.353575] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.475810] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.570083] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.685942] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.731250] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.786531] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.848325] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.922765] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2220.986261] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.063886] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.205690] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.258445] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.311654] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.416223] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.481273] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.529140] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.606218] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.633063] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.749105] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.775300] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2221.919963] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.025222] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.177762] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.219980] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.357503] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.401782] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.605537] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.654796] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.736554] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.806842] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2222.930484] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.098184] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.243597] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.386843] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.431821] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.478688] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.527370] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.620190] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.653847] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.709205] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.770485] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2223.877403] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.067910] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.261108] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.325341] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.527668] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.710390] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.859147] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.938642] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2224.987521] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.060168] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.126389] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.233693] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.429899] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.494432] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.550889] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.671923] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.803497] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.939572] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2225.994202] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.112809] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.186931] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.295924] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.355910] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.416053] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.603882] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2226.862795] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.012813] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.107527] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.143277] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.184469] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.304039] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.359131] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.448774] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.527057] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.563741] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.690375] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.777503] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2227.853556] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.006808] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.071664] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.246179] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.284870] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.418543] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.494319] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.627628] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.782851] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.839122] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2228.887763] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.004136] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.048750] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.107158] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.182374] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.242576] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.343032] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.433445] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.566385] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.620282] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.706281] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.732746] Lustre: lustre-OST0001-osc-ffff92ff592bb800: disconnect after 23s idle [ 2229.742436] Lustre: Skipped 1 previous similar message [ 2229.779706] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2229.870750] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.017921] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.104504] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.153508] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.209330] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.333613] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.410798] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.552077] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.639156] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.680742] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.733435] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.846448] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2230.943576] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.022611] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.281657] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.456833] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.532061] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.646515] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.919028] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2231.998167] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.021103] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.170341] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.244735] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.377375] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.542619] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.720290] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.786471] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2232.919873] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.003703] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.240270] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.277799] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.456847] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.552492] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.701289] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.836495] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.913932] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2233.991789] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2234.033585] rw_seq_cst_vs_d (32991): drop_caches: 3 [ 2234.848279] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 23s idle [ 2243.912930] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 10:01:27 (1779976887) [ 2251.350432] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:01:34 (1779976894) [ 2255.331835] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 21s idle [ 2259.042492] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 10:01:42 (1779976902) [ 2291.792705] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 10:02:15 (1779976935) [ 2292.383289] bash (35494): drop_caches: 3 [ 2295.596286] bash (35494): drop_caches: 3 [ 2298.742803] bash (35494): drop_caches: 3 [ 2301.852062] bash (35494): drop_caches: 3 [ 2305.011966] bash (35494): drop_caches: 3 [ 2308.230835] bash (35494): drop_caches: 3 [ 2311.357682] bash (35494): drop_caches: 3 [ 2311.652192] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 21s idle [ 2314.498581] bash (35494): drop_caches: 3 [ 2317.927937] bash (35494): drop_caches: 3 [ 2321.241531] bash (35494): drop_caches: 3 [ 2324.456905] bash (35494): drop_caches: 3 [ 2327.568593] bash (35494): drop_caches: 3 [ 2330.698356] bash (35494): drop_caches: 3 [ 2333.910108] bash (35494): drop_caches: 3 [ 2339.445228] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 10:03:02 (1779976982) [ 2349.746729] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 10:03:13 (1779976993) [ 2377.173916] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 10:03:40 (1779977020) [ 2384.897150] Lustre: DEBUG MARKER: loop 5 [ 2389.181333] Lustre: DEBUG MARKER: loop 10 [ 2393.568793] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 24s idle [ 2394.185352] Lustre: DEBUG MARKER: loop 15 [ 2398.845206] Lustre: DEBUG MARKER: loop 20 [ 2406.093645] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 10:04:09 (1779977049) [ 2413.264649] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 10:04:17 (1779977057) [ 2419.854806] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 10:04:23 (1779977063) [ 2489.797827] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 10:05:33 (1779977133) [ 2496.464053] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 10:05:40 (1779977140) [ 2502.546768] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 10:05:46 (1779977146) [ 2508.913896] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 10:05:52 (1779977152) [ 2514.942158] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 10:05:58 (1779977158) [ 2521.009591] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 10:06:05 (1779977165) [ 2529.399135] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 10:06:13 (1779977173) [ 2537.239912] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2538.947642] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 10:06:22 (1779977182) [ 2549.027773] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 10:06:32 (1779977192) [ 2549.605272] Lustre: *** cfs_fail_loc=314, val=0*** [ 2550.624679] Lustre: *** cfs_fail_loc=314, val=0*** [ 2550.628048] Lustre: Skipped 2 previous similar messages [ 2559.415283] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 10:06:42 (1779977202) [ 2567.650700] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 22s idle [ 2567.669460] Lustre: Skipped 5 previous similar messages [ 2569.370742] Lustre: *** cfs_fail_loc=314, val=0*** [ 2569.458543] LustreError: lustre-OST0000-osc-ffff92ff592bb800: operation ldlm_enqueue to node 192.168.206.151@tcp failed: rc = -107 [ 2569.465448] Lustre: lustre-OST0000-osc-ffff92ff592bb800: Connection to lustre-OST0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2569.482156] LustreError: lustre-OST0000-osc-ffff92ff592bb800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2569.509613] LustreError: 46411:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff92ff592bb800: namespace resource [0x280000401:0x37:0x0].0x0 (ffff92ff48a81c00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2569.518305] Lustre: lustre-OST0000-osc-ffff92ff592bb800: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [ 2577.235819] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 10:07:00 (1779977220) [ 2577.565098] LustreError: 47002:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2580.600979] LustreError: 47002:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2586.549456] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 10:07:10 (1779977230) [ 2594.633562] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 10:07:18 (1779977238) [ 2602.568626] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2604.526743] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 10:07:28 (1779977248) [ 2606.040529] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2607.765298] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 10:07:31 (1779977251) [ 2613.737532] Lustre: lustre-MDT0000-mdc-ffff92ff5a7a0000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2618.868161] LustreError: MGC192.168.206.151@tcp: Connection to MGS (at 192.168.206.151@tcp) was lost; in progress operations using this service will fail [ 2618.895250] Lustre: Evicted from MGS (at 192.168.206.151@tcp) after server handle changed from 0x9dcdbcd71f0c74e1 to 0x9dcdbcd71f1740fa [ 2618.913856] Lustre: MGC192.168.206.151@tcp: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [ 2623.948083] Lustre: lustre-MDT0000-mdc-ffff92ff592bb800: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [ 2656.313503] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 10:08:19 (1779977299) [ 2705.895859] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 10:09:09 (1779977349) [ 2727.128247] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 10:09:30 (1779977370) [ 2741.731938] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 20s idle [ 2741.740611] Lustre: Skipped 2 previous similar messages [ 2781.630186] Lustre: lustre-OST0000-osc-ffff92ff592bb800: Connection to lustre-OST0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2781.651663] Lustre: Skipped 1 previous similar message [ 2781.668398] LustreError: lustre-OST0000-osc-ffff92ff592bb800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2781.683615] LustreError: lustre-OST0000-osc-ffff92ff5a7a0000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2781.691302] Lustre: lustre-OST0000-osc-ffff92ff592bb800: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [ 2781.703691] Lustre: Skipped 1 previous similar message [ 2796.990932] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: Connection to lustre-OST0001 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2797.019847] Lustre: Skipped 1 previous similar message [ 2797.037550] LustreError: lustre-OST0001-osc-ffff92ff5a7a0000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2797.058534] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [ 2813.629678] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2815.038185] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid in IDLE state after 0 sec [ 2818.454507] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2819.826681] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid in FULL state after 0 sec [ 2824.561205] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2826.086093] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid in IDLE state after 0 sec [ 2829.792464] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2831.238674] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid in FULL state after 0 sec [ 2841.891278] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2843.487698] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff92ff592bb800.ost_server_uuid in IDLE state after 0 sec [ 2847.849512] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid 50 [ 2849.379321] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff92ff592bb800.ost_server_uuid in FULL state after 0 sec [ 2851.118527] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 10:11:34 (1779977494) [ 2853.924782] Lustre: DEBUG MARKER: Race attempt 0 [ 2856.978515] Lustre: DEBUG MARKER: Wait for 57485 57523 for 60 sec... [ 2925.061170] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 10:12:48 (1779977568) [ 2934.147970] Lustre: DEBUG MARKER: start test - cycle (0) [ 2959.638809] Lustre: DEBUG MARKER: start test - cycle (1) [ 2980.979417] Lustre: DEBUG MARKER: start test - cycle (2) [ 3001.944438] Lustre: DEBUG MARKER: start test - cycle (3) [ 3027.543770] Lustre: DEBUG MARKER: start test - cycle (4) [ 3028.448338] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 20s idle [ 3028.457059] Lustre: Skipped 3 previous similar messages [ 3048.700932] Lustre: DEBUG MARKER: start test - cycle (5) [ 3072.907286] Lustre: DEBUG MARKER: start test - cycle (6) [ 3092.937678] Lustre: DEBUG MARKER: start test - cycle (7) [ 3112.972803] Lustre: DEBUG MARKER: start test - cycle (8) [ 3132.955837] Lustre: DEBUG MARKER: start test - cycle (9) [ 3154.269566] Lustre: DEBUG MARKER: start test - cycle (10) [ 3179.579214] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:17:03 (1779977823) [ 3255.375836] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 10:18:18 (1779977898) [ 3262.760102] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 10:18:26 (1779977906) [ 3271.793389] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 10:18:35 (1779977915) [ 3281.399719] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 10:18:44 (1779977924) [ 3281.741908] Lustre: *** cfs_fail_loc=411, val=0*** [ 3288.637429] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3290.379709] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 10:18:54 (1779977934) [ 3308.267698] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 10:19:11 (1779977951) [ 3328.149136] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 10:19:31 (1779977971) [ 3343.799343] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 10:19:47 (1779977987) [ 3362.431580] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 10:20:05 (1779978005) [ 3376.833844] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 10:20:20 (1779978020) [ 3391.876401] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 10:20:35 (1779978035) [ 3406.970878] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 10:20:50 (1779978050) [ 3434.953306] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 10:21:18 (1779978078) [ 3449.220747] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 10:21:32 (1779978092) [ 3463.165970] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 10:21:46 (1779978106) [ 3477.676293] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 10:22:00 (1779978120) [ 3494.714211] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 10:22:18 (1779978138) [ 4108.775050] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 21s idle [ 4108.805039] Lustre: Skipped 16 previous similar messages [ 4635.489563] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 10:41:18 (1779979278) [ 4653.677517] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 10:41:36 (1779979296) [ 4672.189058] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 10:41:55 (1779979315) [ 4691.553422] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 10:42:14 (1779979334) [ 4706.448996] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 10:42:30 (1779979350) [ 4721.475858] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 10:42:45 (1779979365) [ 4738.533680] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 23s idle [ 4738.549763] Lustre: Skipped 2 previous similar messages [ 4738.848155] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 10:43:01 (1779979381) [ 4756.113604] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 10:43:19 (1779979399) [ 4772.097414] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 10:43:35 (1779979415) [ 4904.588780] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 10:45:47 (1779979547) [ 4921.530646] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 10:46:05 (1779979565) [ 4938.522262] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 10:46:21 (1779979581) [ 4956.933215] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 10:46:40 (1779979600) [ 4978.622770] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 10:47:01 (1779979621) [ 4996.006959] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 10:47:19 (1779979639) [ 5010.605081] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 10:47:34 (1779979654) [ 5025.899730] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 10:47:49 (1779979669) [ 5040.492404] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 10:48:03 (1779979683) [ 5191.710618] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 10:50:34 (1779979834) [ 5445.091325] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 24s idle [ 5445.096209] Lustre: Skipped 8 previous similar messages [ 6079.968424] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 20s idle [ 6079.986745] Lustre: Skipped 10 previous similar messages [ 6460.146907] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:11:43 (1779981103) [ 6472.000579] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:11:55 (1779981115) [ 6485.130496] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:12:08 (1779981128) [ 6502.627476] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:12:25 (1779981145) [ 6516.389324] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:12:39 (1779981159) [ 6530.385810] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:12:53 (1779981173) [ 6545.951112] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:13:09 (1779981189) [ 6559.565332] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:13:23 (1779981203) [ 6574.303794] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:13:37 (1779981217) [ 6587.025326] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:13:50 (1779981230) [ 6745.584782] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:16:29 (1779981389) [ 6756.820693] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:16:40 (1779981400) [ 6768.559638] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:16:52 (1779981412) [ 6779.924098] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:17:03 (1779981423) [ 6792.940508] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:17:16 (1779981436) [ 6809.989861] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:17:33 (1779981453) [ 6812.128352] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 24s idle [ 6812.138351] Lustre: Skipped 8 previous similar messages [ 6822.442096] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:17:46 (1779981466) [ 6835.916483] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:17:59 (1779981479) [ 6848.865976] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:18:12 (1779981492) [ 8078.977497] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:38:42 (1779982722) [ 8092.128400] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 20s idle [ 8092.139419] Lustre: Skipped 3 previous similar messages [ 8092.990929] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:38:56 (1779982736) [ 8108.527814] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:39:11 (1779982751) [ 8122.864575] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:39:26 (1779982766) [ 8136.043774] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:39:39 (1779982779) [ 8148.786282] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:39:52 (1779982792) [ 8163.409936] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:40:06 (1779982806) [ 8174.049403] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 23s idle [ 8174.055521] Lustre: Skipped 2 previous similar messages [ 8177.417200] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:40:20 (1779982820) [ 8192.221825] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:40:35 (1779982835) [ 8205.740916] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:40:49 (1779982849) [ 8218.230172] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:41:02 (1779982862) [ 8232.203880] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:41:16 (1779982876) [ 8244.576800] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:41:28 (1779982888) [ 8256.806724] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:41:40 (1779982900) [ 8269.797382] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:41:53 (1779982913) [ 8283.420383] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:42:07 (1779982927) [ 8300.489770] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:42:24 (1779982944) [ 8300.708045] LustreError: 6163:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 8302.808168] LustreError: 6163:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 8312.652437] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:42:36 (1779982956) [ 8322.522431] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:42:46 (1779982966) [ 8323.218859] LustreError: 238436:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8327.312208] LustreError: 238436:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8327.390720] LustreError: 238436:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8331.465960] LustreError: 238436:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8331.533106] LustreError: 238442:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8335.616334] LustreError: 238442:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8342.977430] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:43:06 (1779982986) [ 8355.216118] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:43:18 (1779982998) [ 8363.194924] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:43:27 (1779983007) [ 8363.495924] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 21s idle [ 8363.505742] Lustre: Skipped 7 previous similar messages [ 8371.818560] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:43:35 (1779983015) [ 8403.622477] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:44:07 (1779983047) [ 8415.802878] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:44:19 (1779983059) [ 8428.063837] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:44:31 (1779983071) [ 8446.557569] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:44:50 (1779983090) [ 8462.021056] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 11:45:05 (1779983105) [ 8481.418868] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 11:45:24 (1779983124) [ 8499.208623] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 11:45:42 (1779983142) [ 8521.555171] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 11:46:05 (1779983165) [ 8544.745858] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 11:46:28 (1779983188) [ 8651.178957] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:48:15 (1779983295) [ 8659.734105] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 8665.949666] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:48:29 (1779983309) [ 8673.725490] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:48:37 (1779983317) [ 8681.252726] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:48:44 (1779983324) [ 8688.178298] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:48:51 (1779983331) [ 8730.843383] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:49:34 (1779983374) [ 8794.130608] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:50:37 (1779983437) [ 8802.594552] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:50:45 (1779983445) [ 8809.371376] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:50:53 (1779983453) [ 8812.809653] LustreError: lustre-MDT0000-mdc-ffff92ff5a7a0000: operation ldlm_enqueue to node 192.168.206.151@tcp failed: rc = -35 [ 8820.493572] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:51:04 (1779983464) [ 8821.221744] LustreError: 2406:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8823.312138] LustreError: 2406:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8834.330987] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:51:17 (1779983477) [ 8844.786032] Lustre: lustre-OST0000-osc-ffff92ff592bb800: disconnect after 24s idle [ 9032.146439] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:54:35 (1779983675) [ 9042.827304] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:54:45 (1779983685) [ 9056.047903] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:54:59 (1779983699) [ 9072.518507] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:55:16 (1779983716) [ 9089.693580] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:55:33 (1779983733) [ 9116.495680] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:55:59 (1779983759) [ 9139.918172] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:56:23 (1779983783) [ 9151.370989] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:56:34 (1779983794) [ 9164.030860] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:56:47 (1779983807) [ 9189.608399] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:57:12 (1779983832) [ 9249.116871] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:58:11 (1779983891) [ 9385.076332] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 12:00:28 (1779984028) [ 9479.648361] Lustre: lustre-OST0001-osc-ffff92ff5a7a0000: disconnect after 20s idle [ 9479.653134] Lustre: Skipped 12 previous similar messages [ 9516.874598] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 12:02:40 (1779984160) [ 9886.487805] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 12:08:50 (1779984530) [ 9929.157948] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 12:09:32 (1779984572) [ 9970.008971] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 12:10:13 (1779984613) [10006.001532] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 12:10:49 (1779984649) [10083.814343] Lustre: lustre-OST0000-osc-ffff92ff5a7a0000: disconnect after 21s idle [10083.821308] Lustre: Skipped 16 previous similar messages [10084.122905] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 12:12:07 (1779984727) [10148.944562] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 12:13:12 (1779984792) [10272.432502] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 12:15:16 (1779984916) [10276.699132] LustreError: 283502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a7a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10276.781283] Lustre: Unmounted lustre-client [10280.921348] LustreError: 283516:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff592bb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10280.931656] LustreError: 283516:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10281.032199] Lustre: Unmounted lustre-client [10410.146977] Lustre: Mounted lustre-client [10413.155563] Lustre: Mounted lustre-client [10417.029985] Lustre: DEBUG MARKER: Using TIMEOUT=20 [10517.483304] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 12:19:21 (1779985161) [10536.213174] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 12:19:39 (1779985179) [10546.385792] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 12:19:49 (1779985189) [10609.045375] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 12:20:51 (1779985251) [10688.849760] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 12:22:11 (1779985331) [10705.512924] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 12:22:28 (1779985348) [10836.090152] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 12:24:38 (1779985478) [10878.402580] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 12:25:21 (1779985521) [10932.678755] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 12:26:16 (1779985576) [10940.605779] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 12:26:24 (1779985584) [10955.747073] Lustre: lustre-OST0000-osc-ffff92ff815c0000: disconnect after 23s idle [10955.757615] Lustre: Skipped 12 previous similar messages [10957.235934] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 12:26:41 (1779985601) [10967.625931] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 12:26:51 (1779985611) [10969.798645] LustreError: 300821:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x194:0x0]: rc = -5 [10969.806134] LustreError: 300821:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10970.568663] LustreError: 300831:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x194:0x0]: rc = -5 [10970.577653] LustreError: 300831:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10970.590737] LustreError: 300831:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10970.597385] LustreError: 300831:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 1 previous similar message [10971.913978] LustreError: 300851:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1a0:0x0]: rc = -5 [10971.924440] LustreError: 300851:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [10971.935818] LustreError: 300851:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10971.946463] LustreError: 300851:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 3 previous similar messages [10973.928810] LustreError: 300648:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1ab:0x0]: rc = -5 [10973.937603] LustreError: 300648:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 6 previous similar messages [10973.940953] LustreError: 300648:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10973.954106] LustreError: 300648:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 6 previous similar messages [10977.996388] LustreError: 300934:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x4f:0x0]: rc = -5 [10978.020694] LustreError: 300934:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 8 previous similar messages [10978.044222] LustreError: 300934:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10978.058584] LustreError: 300934:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 8 previous similar messages [10986.281673] LustreError: 301074:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1dd:0x0]: rc = -5 [10986.293034] LustreError: 301074:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 27 previous similar messages [10986.299979] LustreError: 301074:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10986.308283] LustreError: 301074:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 27 previous similar messages [11002.334047] LustreError: 301340:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x219:0x0]: rc = -5 [11002.347604] LustreError: 301340:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 51 previous similar messages [11002.355458] LustreError: 301340:0:(llite_lib.c:3744:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11002.358991] LustreError: 301340:0:(llite_lib.c:3744:ll_prep_inode()) Skipped 51 previous similar messages [11034.447907] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 12:27:58 (1779985678) [11040.592225] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 12:28:04 (1779985684) [11282.012957] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 12:32:05 (1779985925) [11283.726838] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [11286.046518] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 12:32:09 (1779985929) [11550.042306] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 12:36:33 (1779986193) [11557.794446] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 12:36:41 (1779986201) [11570.150239] Lustre: lustre-OST0001-osc-ffff92ff5826a000: disconnect after 23s idle [11570.155650] Lustre: Skipped 3 previous similar messages [11685.949076] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 12:38:48 (1779986328) [11699.759367] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 12:39:03 (1779986343) [11712.107877] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 12:39:15 (1779986355) [11898.982678] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 12:42:22 (1779986542) [12085.915553] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 12:45:29 (1779986729) [12093.071880] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 12:45:36 (1779986736) [12111.503298] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 12:45:55 (1779986755) [12112.014063] Lustre: DEBUG MARKER: write [12112.087346] LustreError: 285958:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [12114.141591] Lustre: DEBUG MARKER: kill 331714 [12114.160146] LustreError: 331714:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [12117.128079] LustreError: 285958:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [12120.256176] LustreError: 331714:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [12128.369756] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 12:46:11 (1779986771) [12131.215159] LustreError: 332328:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [12141.312293] LustreError: 332328:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [12147.267468] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 12:46:31 (1779986791) [12147.705948] LustreError: 332917:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [12149.792075] LustreError: 332917:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [12159.945558] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 12:46:43 (1779986803) [12162.139558] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [12164.380751] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 12:46:47 (1779986807) [12172.837126] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 12:46:56 (1779986816) [12180.465967] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 12:47:04 (1779986824) [12187.595753] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 12:47:11 (1779986831) [12193.881895] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 12:47:17 (1779986837) [12201.015780] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 12:47:24 (1779986844) [12209.683914] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 12:47:33 (1779986853) [12220.623302] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 12:47:44 (1779986864) [12231.368639] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 12:47:54 (1779986874) [12233.582919] Lustre: *** cfs_fail_loc=415, val=0*** [12245.836249] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 12:48:09 (1779986889) [12273.261579] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 12:48:37 (1779986917) [12273.763160] LustreError: 285958:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [12273.770511] LustreError: 285958:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [12278.770190] LustreError: 285235:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [12288.872107] LustreError: 285234:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [12288.886381] LustreError: 285234:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 7 previous similar messages [12295.432618] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 12:48:59 (1779986939) [12303.255377] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 12:49:06 (1779986946) [12312.824370] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 12:49:16 (1779986956) [12320.298262] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 12:49:23 (1779986963) [12330.257448] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 12:49:33 (1779986973) [12342.740328] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 12:49:46 (1779986986) [12343.534421] LustreError: 343665:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [12343.545764] LustreError: 343665:0:(osc_request.c:2968:osc_build_rpc()) Skipped 6 previous similar messages [12347.608290] LustreError: 343665:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [12347.624317] LustreError: 343665:0:(osc_request.c:2968:osc_build_rpc()) Skipped 1 previous similar message [12354.669922] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 12:49:58 (1779986998) [12359.687801] LustreError: 344375:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12359.706333] LustreError: 344375:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12359.825333] Lustre: Unmounted lustre-client [12362.558199] LustreError: 344396:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5826a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12362.572176] LustreError: 344396:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12362.695094] Lustre: Unmounted lustre-client [12364.475154] Lustre: DEBUG MARKER: Iteration 1 [12364.771823] LustreError: 344558:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12364.777803] LustreError: 344560:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12364.799083] LustreError: 344558:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [12365.172886] Lustre: Mounted lustre-client [12366.945972] LustreError: 344669:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a5d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12366.961492] LustreError: 344669:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12367.074973] Lustre: Unmounted lustre-client [12370.323868] Key type lgssc unregistered [12370.569965] LNet: 344920:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12370.576926] LNetError: 344920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12370.610824] LNet: Removed LNI 192.168.206.51@tcp [12371.467246] Key type .llcrypt unregistered [12371.471336] Key type ._llcrypt unregistered [12372.554703] Key type ._llcrypt registered [12372.609295] Key type .llcrypt registered [12372.987624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12373.005715] alg: No test for adler32 (adler32-zlib) [12374.394704] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12375.066959] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12376.824260] Key type lgssc registered [12378.427103] Lustre: Echo OBD driver; http://www.lustre.org/ [12391.708800] Lustre: DEBUG MARKER: Iteration 2 [12391.985838] LustreError: 345754:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12391.988704] LustreError: 345755:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12392.001118] LustreError: 345754:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12394.363754] Lustre: Mounted lustre-client [12395.788704] LustreError: 345866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff8166e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12395.894098] Lustre: Unmounted lustre-client [12398.517652] Key type lgssc unregistered [12398.856028] LNet: 346114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12398.863790] LNetError: 346114:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12398.900421] LNet: Removed LNI 192.168.206.51@tcp [12399.633185] Key type .llcrypt unregistered [12399.635290] Key type ._llcrypt unregistered [12400.458568] Key type ._llcrypt registered [12400.460494] Key type .llcrypt registered [12400.972087] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12400.992270] alg: No test for adler32 (adler32-zlib) [12402.034278] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12402.252571] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12403.945095] Key type lgssc registered [12405.336661] Lustre: Echo OBD driver; http://www.lustre.org/ [12416.848460] Lustre: DEBUG MARKER: Iteration 3 [12417.303812] LustreError: 346948:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12417.306137] LustreError: 346950:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12417.336388] LustreError: 346948:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12418.653308] Lustre: Mounted lustre-client [12420.514424] LustreError: 347057:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a71f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12420.685079] Lustre: Unmounted lustre-client [12423.356172] Key type lgssc unregistered [12423.677987] LNet: 347308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12423.700019] LNetError: 347308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12423.743416] LNet: Removed LNI 192.168.206.51@tcp [12424.445371] Key type .llcrypt unregistered [12424.452316] Key type ._llcrypt unregistered [12425.466537] Key type ._llcrypt registered [12425.472279] Key type .llcrypt registered [12426.064719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12426.091913] alg: No test for adler32 (adler32-zlib) [12427.323368] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12427.569560] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12429.392249] Key type lgssc registered [12430.730945] Lustre: Echo OBD driver; http://www.lustre.org/ [12441.817679] Lustre: DEBUG MARKER: Iteration 4 [12442.101158] LustreError: 348142:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12442.103633] LustreError: 348141:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12442.112130] LustreError: 348142:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12444.369762] Lustre: Mounted lustre-client [12445.758704] LustreError: 348249:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff76d26800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12445.858339] Lustre: Unmounted lustre-client [12448.136401] Key type lgssc unregistered [12448.363546] LNet: 348500:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12448.369739] LNetError: 348500:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12448.382400] LNet: Removed LNI 192.168.206.51@tcp [12448.958910] Key type .llcrypt unregistered [12448.961611] Key type ._llcrypt unregistered [12449.623351] Key type ._llcrypt registered [12449.643147] Key type .llcrypt registered [12449.891399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12449.905385] alg: No test for adler32 (adler32-zlib) [12450.922767] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12451.120860] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12452.776196] Key type lgssc registered [12453.834910] Lustre: Echo OBD driver; http://www.lustre.org/ [12465.223566] Lustre: DEBUG MARKER: Iteration 5 [12465.744457] LustreError: 349333:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12465.744642] LustreError: 349334:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12465.758816] LustreError: 349333:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [12467.100953] Lustre: Mounted lustre-client [12469.057039] LustreError: 349444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff45874800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12469.138473] Lustre: Unmounted lustre-client [12472.473862] Key type lgssc unregistered [12472.846573] LNet: 349694:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12472.859912] LNetError: 349694:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12472.885115] LNet: Removed LNI 192.168.206.51@tcp [12473.503141] Key type .llcrypt unregistered [12473.510501] Key type ._llcrypt unregistered [12474.743880] Key type ._llcrypt registered [12474.746566] Key type .llcrypt registered [12475.236631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12475.248690] alg: No test for adler32 (adler32-zlib) [12476.425197] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12476.771362] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12478.448156] Key type lgssc registered [12480.040752] Lustre: Echo OBD driver; http://www.lustre.org/ [12491.673128] Lustre: DEBUG MARKER: Iteration 6 [12491.930782] LustreError: 350528:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12491.932115] LustreError: 350529:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12491.945246] LustreError: 350528:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12493.149401] Lustre: Mounted lustre-client [12494.331512] LustreError: 350644:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5928a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12494.426451] Lustre: Unmounted lustre-client [12496.463118] Key type lgssc unregistered [12496.654036] LNet: 350888:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12496.659211] LNetError: 350888:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12496.676977] LNet: Removed LNI 192.168.206.51@tcp [12497.265723] Key type .llcrypt unregistered [12497.268151] Key type ._llcrypt unregistered [12497.805652] Key type ._llcrypt registered [12497.814978] Key type .llcrypt registered [12498.206462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12498.216531] alg: No test for adler32 (adler32-zlib) [12499.307853] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12499.515344] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12501.200195] Key type lgssc registered [12502.296825] Lustre: Echo OBD driver; http://www.lustre.org/ [12512.414421] Lustre: DEBUG MARKER: Iteration 7 [12512.819855] LustreError: 351722:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12512.821747] LustreError: 351721:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12512.837039] LustreError: 351722:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12514.142710] Lustre: Mounted lustre-client [12515.338991] LustreError: 351834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff59257000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12515.426469] Lustre: Unmounted lustre-client [12517.925683] Key type lgssc unregistered [12518.177325] LNet: 352084:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12518.199804] LNetError: 352084:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12518.219864] LNet: Removed LNI 192.168.206.51@tcp [12519.065240] Key type .llcrypt unregistered [12519.068472] Key type ._llcrypt unregistered [12519.899266] Key type ._llcrypt registered [12519.902214] Key type .llcrypt registered [12520.180714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12520.201746] alg: No test for adler32 (adler32-zlib) [12521.278890] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12521.506745] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12523.192247] Key type lgssc registered [12524.329768] Lustre: Echo OBD driver; http://www.lustre.org/ [12534.827133] Lustre: DEBUG MARKER: Iteration 8 [12535.155806] LustreError: 352918:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12535.157286] LustreError: 352919:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12535.175892] LustreError: 352918:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [12536.415505] Lustre: Mounted lustre-client [12537.591349] LustreError: 353024:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a71f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12537.690375] Lustre: Unmounted lustre-client [12539.880630] Key type lgssc unregistered [12540.085471] LNet: 353275:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12540.096082] LNetError: 353275:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12540.116878] LNet: Removed LNI 192.168.206.51@tcp [12540.636204] Key type .llcrypt unregistered [12540.638140] Key type ._llcrypt unregistered [12541.425784] Key type ._llcrypt registered [12541.436237] Key type .llcrypt registered [12541.758865] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12541.776208] alg: No test for adler32 (adler32-zlib) [12542.789960] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12542.950560] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12544.600165] Key type lgssc registered [12545.645269] Lustre: Echo OBD driver; http://www.lustre.org/ [12556.008647] Lustre: DEBUG MARKER: Iteration 9 [12556.439786] LustreError: 354109:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12556.439820] LustreError: 354110:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12556.458250] LustreError: 354109:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [12557.708584] Lustre: Mounted lustre-client [12559.161668] LustreError: 354222:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff50608000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12559.247587] Lustre: Unmounted lustre-client [12562.264402] Key type lgssc unregistered [12562.595880] LNet: 354472:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12562.617180] LNetError: 354472:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12562.636758] LNet: Removed LNI 192.168.206.51@tcp [12563.730211] Key type .llcrypt unregistered [12563.733361] Key type ._llcrypt unregistered [12564.635585] Key type ._llcrypt registered [12564.651507] Key type .llcrypt registered [12565.145138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12565.186092] alg: No test for adler32 (adler32-zlib) [12566.284470] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12566.600123] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12568.416456] Key type lgssc registered [12569.668890] Lustre: Echo OBD driver; http://www.lustre.org/ [12582.350588] Lustre: DEBUG MARKER: Iteration 10 [12582.738843] LustreError: 355304:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12582.742477] LustreError: 355307:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12582.753123] LustreError: 355304:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12584.059965] Lustre: Mounted lustre-client [12585.829775] LustreError: 355420:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff59250000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12585.953460] Lustre: Unmounted lustre-client [12589.407537] Key type lgssc unregistered [12589.794314] LNet: 355669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12589.805517] LNetError: 355669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12589.830652] LNet: Removed LNI 192.168.206.51@tcp [12590.681175] Key type .llcrypt unregistered [12590.685953] Key type ._llcrypt unregistered [12591.644748] Key type ._llcrypt registered [12591.650337] Key type .llcrypt registered [12592.032411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12592.051538] alg: No test for adler32 (adler32-zlib) [12593.061502] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12593.298181] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12594.928305] Key type lgssc registered [12596.018259] Lustre: Echo OBD driver; http://www.lustre.org/ [12607.454995] Lustre: DEBUG MARKER: Iteration 11 [12607.746384] LustreError: 356504:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12607.746917] LustreError: 356503:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12607.759873] LustreError: 356504:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12610.135063] Lustre: Mounted lustre-client [12610.143826] Lustre: Skipped 1 previous similar message [12611.431990] LustreError: 356611:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff4a688800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12611.562423] Lustre: Unmounted lustre-client [12615.009847] Key type lgssc unregistered [12615.314405] LNet: 356863:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12615.325329] LNetError: 356863:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12615.344977] LNet: Removed LNI 192.168.206.51@tcp [12615.978215] Key type .llcrypt unregistered [12615.983766] Key type ._llcrypt unregistered [12616.746729] Key type ._llcrypt registered [12616.753977] Key type .llcrypt registered [12617.228632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12617.246790] alg: No test for adler32 (adler32-zlib) [12618.391313] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12618.673952] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12620.400444] Key type lgssc registered [12621.693662] Lustre: Echo OBD driver; http://www.lustre.org/ [12634.169312] Lustre: DEBUG MARKER: Iteration 12 [12634.582979] LustreError: 357697:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12634.586600] LustreError: 357696:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12634.594428] LustreError: 357697:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12635.901105] Lustre: Mounted lustre-client [12637.472508] LustreError: 357804:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a427800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12637.615639] Lustre: Unmounted lustre-client [12640.297687] Key type lgssc unregistered [12640.621497] LNet: 358055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12640.628540] LNetError: 358055:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12640.645101] LNet: Removed LNI 192.168.206.51@tcp [12641.263144] Key type .llcrypt unregistered [12641.270114] Key type ._llcrypt unregistered [12642.108612] Key type ._llcrypt registered [12642.129052] Key type .llcrypt registered [12642.504550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12642.541480] alg: No test for adler32 (adler32-zlib) [12643.694705] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12644.030575] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12645.712244] Key type lgssc registered [12647.009621] Lustre: Echo OBD driver; http://www.lustre.org/ [12659.227541] Lustre: DEBUG MARKER: Iteration 13 [12659.692243] LustreError: 358888:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12659.692952] LustreError: 358889:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12659.715621] LustreError: 358888:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [12661.082239] Lustre: Mounted lustre-client [12662.714141] LustreError: 358994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff8166b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12662.872596] Lustre: Unmounted lustre-client [12665.738344] Key type lgssc unregistered [12666.106237] LNet: 359244:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12666.118264] LNetError: 359244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12666.153448] LNet: Removed LNI 192.168.206.51@tcp [12667.037348] Key type .llcrypt unregistered [12667.039352] Key type ._llcrypt unregistered [12667.923648] Key type ._llcrypt registered [12667.928273] Key type .llcrypt registered [12668.321462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12668.346560] alg: No test for adler32 (adler32-zlib) [12669.450191] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12669.758574] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12671.464247] Key type lgssc registered [12672.678747] Lustre: Echo OBD driver; http://www.lustre.org/ [12683.668133] Lustre: DEBUG MARKER: Iteration 14 [12683.907663] LustreError: 360079:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12683.907758] LustreError: 360078:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12683.918578] LustreError: 360079:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12685.115587] Lustre: Mounted lustre-client [12686.383643] LustreError: 360183:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff59253800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12686.470115] Lustre: Unmounted lustre-client [12688.803468] Key type lgssc unregistered [12689.087111] LNet: 360434:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12689.092847] LNetError: 360434:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12689.117845] LNet: Removed LNI 192.168.206.51@tcp [12689.660151] Key type .llcrypt unregistered [12689.662443] Key type ._llcrypt unregistered [12690.119207] Key type ._llcrypt registered [12690.124568] Key type .llcrypt registered [12690.565110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12690.577409] alg: No test for adler32 (adler32-zlib) [12691.643933] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12691.861697] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12693.504213] Key type lgssc registered [12694.439076] Lustre: Echo OBD driver; http://www.lustre.org/ [12704.476224] Lustre: DEBUG MARKER: Iteration 15 [12704.783552] LustreError: 361267:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12704.787181] LustreError: 361269:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12704.793716] LustreError: 361267:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12706.026700] Lustre: Mounted lustre-client [12707.340969] LustreError: 361380:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff50a8a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12707.419928] Lustre: Unmounted lustre-client [12709.825175] Key type lgssc unregistered [12710.033338] LNet: 361629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12710.041300] LNetError: 361629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12710.056262] LNet: Removed LNI 192.168.206.51@tcp [12710.560978] Key type .llcrypt unregistered [12710.564769] Key type ._llcrypt unregistered [12711.165444] Key type ._llcrypt registered [12711.169619] Key type .llcrypt registered [12711.477926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12711.492058] alg: No test for adler32 (adler32-zlib) [12712.495792] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12712.663239] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12714.304516] Key type lgssc registered [12715.363228] Lustre: Echo OBD driver; http://www.lustre.org/ [12724.623529] Lustre: DEBUG MARKER: Iteration 16 [12725.036889] LustreError: 362462:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12725.041299] LustreError: 362463:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12725.062200] LustreError: 362462:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12726.347872] Lustre: Mounted lustre-client [12727.651541] LustreError: 362576:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff45540800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12727.760527] Lustre: Unmounted lustre-client [12730.134157] Key type lgssc unregistered [12730.410532] LNet: 362820:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12730.416351] LNetError: 362820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12730.437188] LNet: Removed LNI 192.168.206.51@tcp [12730.968185] Key type .llcrypt unregistered [12730.970863] Key type ._llcrypt unregistered [12731.394687] Key type ._llcrypt registered [12731.398375] Key type .llcrypt registered [12731.601279] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12731.728799] alg: No test for adler32 (adler32-zlib) [12732.708251] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12732.897783] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12734.536384] Key type lgssc registered [12735.509629] Lustre: Echo OBD driver; http://www.lustre.org/ [12745.761162] Lustre: DEBUG MARKER: Iteration 17 [12746.023625] LustreError: 363650:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12746.023658] LustreError: 363652:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12746.042953] LustreError: 363650:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [12747.288806] Lustre: Mounted lustre-client [12748.636362] LustreError: 363761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48a17800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12748.808173] Lustre: Unmounted lustre-client [12751.026658] Key type lgssc unregistered [12751.230407] LNet: 364010:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12751.244159] LNetError: 364010:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12751.265098] LNet: Removed LNI 192.168.206.51@tcp [12751.821452] Key type .llcrypt unregistered [12751.824086] Key type ._llcrypt unregistered [12752.608292] Key type ._llcrypt registered [12752.610881] Key type .llcrypt registered [12752.963020] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12752.977975] alg: No test for adler32 (adler32-zlib) [12754.051629] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12754.252523] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12755.920174] Key type lgssc registered [12756.897094] Lustre: Echo OBD driver; http://www.lustre.org/ [12766.264737] Lustre: DEBUG MARKER: Iteration 18 [12766.474896] LustreError: 364841:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12766.477436] LustreError: 364843:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12766.483598] LustreError: 364841:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12767.694340] Lustre: Mounted lustre-client [12768.911731] LustreError: 364952:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a71c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12769.016459] Lustre: Unmounted lustre-client [12771.202488] Key type lgssc unregistered [12771.409113] LNet: 365200:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12771.416326] LNetError: 365200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12771.434041] LNet: Removed LNI 192.168.206.51@tcp [12772.043229] Key type .llcrypt unregistered [12772.047080] Key type ._llcrypt unregistered [12773.177202] Key type ._llcrypt registered [12773.182543] Key type .llcrypt registered [12773.363295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12773.376562] alg: No test for adler32 (adler32-zlib) [12774.419293] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12774.640685] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12776.304212] Key type lgssc registered [12777.522683] Lustre: Echo OBD driver; http://www.lustre.org/ [12787.732320] Lustre: DEBUG MARKER: Iteration 19 [12788.039552] LustreError: 366034:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12788.039648] LustreError: 366035:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12788.049085] LustreError: 366034:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12790.410503] Lustre: Mounted lustre-client [12790.412665] Lustre: Skipped 1 previous similar message [12791.618670] LustreError: 366144:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12791.695215] Lustre: Unmounted lustre-client [12794.288636] Key type lgssc unregistered [12794.528076] LNet: 366394:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12794.535982] LNetError: 366394:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12794.557087] LNet: Removed LNI 192.168.206.51@tcp [12795.133461] Key type .llcrypt unregistered [12795.136422] Key type ._llcrypt unregistered [12795.841103] Key type ._llcrypt registered [12795.844852] Key type .llcrypt registered [12796.272590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12796.290295] alg: No test for adler32 (adler32-zlib) [12797.303840] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12797.518990] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12799.168200] Key type lgssc registered [12800.223326] Lustre: Echo OBD driver; http://www.lustre.org/ [12809.523286] Lustre: DEBUG MARKER: Iteration 20 [12810.047887] LustreError: 367228:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12810.047908] LustreError: 367227:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12810.069676] LustreError: 367228:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12811.474903] Lustre: Mounted lustre-client [12811.474903] Lustre: Mounted lustre-client [12812.890110] LustreError: 367339:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff59251000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12812.982426] Lustre: Unmounted lustre-client [12815.189724] Key type lgssc unregistered [12815.410722] LNet: 367589:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12815.425798] LNetError: 367589:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12815.455905] LNet: Removed LNI 192.168.206.51@tcp [12816.065377] Key type .llcrypt unregistered [12816.071281] Key type ._llcrypt unregistered [12817.198172] Key type ._llcrypt registered [12817.203431] Key type .llcrypt registered [12817.916808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12817.950089] alg: No test for adler32 (adler32-zlib) [12819.090237] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12819.489138] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12821.281092] Key type lgssc registered [12823.138830] Lustre: Echo OBD driver; http://www.lustre.org/ [12839.244139] Lustre: DEBUG MARKER: Iteration 21 [12839.715565] LustreError: 368421:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12839.716041] LustreError: 368423:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12839.733147] LustreError: 368421:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12841.136350] Lustre: Mounted lustre-client [12841.148633] Lustre: Skipped 1 previous similar message [12842.811752] LustreError: 368533:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff7da04000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12842.968539] Lustre: Unmounted lustre-client [12845.821816] Key type lgssc unregistered [12846.055896] LNet: 368783:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12846.062732] LNetError: 368783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12846.078665] LNet: Removed LNI 192.168.206.51@tcp [12846.749543] Key type .llcrypt unregistered [12846.752945] Key type ._llcrypt unregistered [12847.456111] Key type ._llcrypt registered [12847.458199] Key type .llcrypt registered [12847.894196] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12847.904847] alg: No test for adler32 (adler32-zlib) [12849.078786] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12849.211696] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12850.944239] Key type lgssc registered [12852.340365] Lustre: Echo OBD driver; http://www.lustre.org/ [12862.322181] Lustre: DEBUG MARKER: Iteration 22 [12862.689304] LustreError: 369616:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12862.689825] LustreError: 369617:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12862.703746] LustreError: 369616:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [12863.923603] Lustre: Mounted lustre-client [12865.024703] LustreError: 369725:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff7da03000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12865.095183] Lustre: Unmounted lustre-client [12867.582555] Key type lgssc unregistered [12867.902035] LNet: 369976:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12867.915969] LNetError: 369976:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12868.967569] LNet: Removed LNI 192.168.206.51@tcp [12869.729285] Key type .llcrypt unregistered [12869.736272] Key type ._llcrypt unregistered [12870.527179] Key type ._llcrypt registered [12870.531223] Key type .llcrypt registered [12870.971256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12870.987417] alg: No test for adler32 (adler32-zlib) [12872.185193] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12872.445812] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12874.112190] Key type lgssc registered [12875.186333] Lustre: Echo OBD driver; http://www.lustre.org/ [12885.628762] Lustre: DEBUG MARKER: Iteration 23 [12885.971335] LustreError: 370808:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12885.971347] LustreError: 370812:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12885.985183] LustreError: 370808:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12888.244896] Lustre: Mounted lustre-client [12889.473451] LustreError: 370923:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48e68800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12889.548752] Lustre: Unmounted lustre-client [12892.090404] Key type lgssc unregistered [12892.301453] LNet: 371173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12892.312088] LNetError: 371173:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12892.343480] LNet: Removed LNI 192.168.206.51@tcp [12892.943057] Key type .llcrypt unregistered [12892.950237] Key type ._llcrypt unregistered [12893.811277] Key type ._llcrypt registered [12893.815333] Key type .llcrypt registered [12894.221995] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12894.232816] alg: No test for adler32 (adler32-zlib) [12895.276992] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12895.561806] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12897.280339] Key type lgssc registered [12898.360716] Lustre: Echo OBD driver; http://www.lustre.org/ [12909.952529] Lustre: DEBUG MARKER: Iteration 24 [12910.325790] LustreError: 372008:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12910.327364] LustreError: 372009:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12910.358110] LustreError: 372008:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12912.639597] Lustre: Mounted lustre-client [12914.152827] LustreError: 372116:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12914.257786] Lustre: Unmounted lustre-client [12916.926858] Key type lgssc unregistered [12917.145152] LNet: 372367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12917.169273] LNetError: 372367:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12917.208822] LNet: Removed LNI 192.168.206.51@tcp [12917.952538] Key type .llcrypt unregistered [12917.958476] Key type ._llcrypt unregistered [12918.566381] Key type ._llcrypt registered [12918.570351] Key type .llcrypt registered [12918.912484] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12918.936749] alg: No test for adler32 (adler32-zlib) [12920.000538] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12920.297273] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12921.968560] Key type lgssc registered [12923.384551] Lustre: Echo OBD driver; http://www.lustre.org/ [12936.739273] Lustre: DEBUG MARKER: Iteration 25 [12937.275356] LustreError: 373202:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12937.276369] LustreError: 373203:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12937.289448] LustreError: 373202:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12939.655614] Lustre: Mounted lustre-client [12941.513756] LustreError: 373319:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff7da03000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12941.700146] Lustre: Unmounted lustre-client [12944.648073] Key type lgssc unregistered [12945.088042] LNet: 373562:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12945.097664] LNetError: 373562:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12945.126984] LNet: Removed LNI 192.168.206.51@tcp [12946.262724] Key type .llcrypt unregistered [12946.265772] Key type ._llcrypt unregistered [12947.127335] Key type ._llcrypt registered [12947.130355] Key type .llcrypt registered [12947.464670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12947.489922] alg: No test for adler32 (adler32-zlib) [12948.581586] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12948.823699] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12950.585840] Key type lgssc registered [12952.390684] Lustre: Echo OBD driver; http://www.lustre.org/ [12966.106913] Lustre: DEBUG MARKER: Iteration 26 [12966.753165] LustreError: 374397:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12966.753243] LustreError: 374398:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12966.764707] LustreError: 374397:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12968.094192] Lustre: Mounted lustre-client [12970.097830] LustreError: 374506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48cb6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12970.240822] Lustre: Unmounted lustre-client [12973.510150] Key type lgssc unregistered [12973.852964] LNet: 374756:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12973.861162] LNetError: 374756:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12973.886177] LNet: Removed LNI 192.168.206.51@tcp [12974.436218] Key type .llcrypt unregistered [12974.439883] Key type ._llcrypt unregistered [12975.310834] Key type ._llcrypt registered [12975.326837] Key type .llcrypt registered [12975.480968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12975.495383] alg: No test for adler32 (adler32-zlib) [12976.558456] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12976.808576] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [12978.504222] Key type lgssc registered [12979.818514] Lustre: Echo OBD driver; http://www.lustre.org/ [12990.833733] Lustre: DEBUG MARKER: Iteration 27 [12991.086645] LustreError: 375588:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [12991.090586] LustreError: 375589:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [12991.099372] LustreError: 375588:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12992.370293] Lustre: Mounted lustre-client [12992.380557] Lustre: Skipped 1 previous similar message [12993.678193] LustreError: 375696:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff43901800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12993.770979] Lustre: Unmounted lustre-client [12995.979601] Key type lgssc unregistered [12996.196622] LNet: 375944:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12996.210258] LNetError: 375944:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12996.228136] LNet: Removed LNI 192.168.206.51@tcp [12996.756162] Key type .llcrypt unregistered [12996.760148] Key type ._llcrypt unregistered [12997.502207] Key type ._llcrypt registered [12997.506170] Key type .llcrypt registered [12997.826460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12997.834858] alg: No test for adler32 (adler32-zlib) [12998.923267] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [12999.203672] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13000.944198] Key type lgssc registered [13002.150635] Lustre: Echo OBD driver; http://www.lustre.org/ [13011.980558] Lustre: DEBUG MARKER: Iteration 28 [13012.248332] LustreError: 376776:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13012.255886] LustreError: 376778:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13012.264493] LustreError: 376776:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13013.575166] Lustre: Mounted lustre-client [13013.577600] Lustre: Skipped 1 previous similar message [13014.737709] LustreError: 376886:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff59253800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13014.840197] Lustre: Unmounted lustre-client [13017.431629] Key type lgssc unregistered [13017.626537] LNet: 377150:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13017.636216] LNetError: 377150:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13017.655276] LNet: Removed LNI 192.168.206.51@tcp [13018.207177] Key type .llcrypt unregistered [13018.213484] Key type ._llcrypt unregistered [13018.843306] Key type ._llcrypt registered [13018.846537] Key type .llcrypt registered [13019.261835] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13019.294340] alg: No test for adler32 (adler32-zlib) [13020.382807] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13020.676296] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13022.400189] Key type lgssc registered [13023.674163] Lustre: Echo OBD driver; http://www.lustre.org/ [13034.039884] Lustre: DEBUG MARKER: Iteration 29 [13034.396186] LustreError: 377984:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13034.398449] LustreError: 377985:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13034.414794] LustreError: 377984:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [13035.719477] Lustre: Mounted lustre-client [13035.724984] Lustre: Skipped 1 previous similar message [13036.894725] LustreError: 378093:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a71b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13036.961349] Lustre: Unmounted lustre-client [13039.747584] Key type lgssc unregistered [13039.960880] LNet: 378343:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13039.969908] LNetError: 378343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13039.994953] LNet: Removed LNI 192.168.206.51@tcp [13040.573155] Key type .llcrypt unregistered [13040.575600] Key type ._llcrypt unregistered [13041.218498] Key type ._llcrypt registered [13041.231768] Key type .llcrypt registered [13041.651763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13041.672050] alg: No test for adler32 (adler32-zlib) [13042.735245] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13042.979040] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13044.673548] Key type lgssc registered [13046.049166] Lustre: Echo OBD driver; http://www.lustre.org/ [13055.199752] Lustre: DEBUG MARKER: Iteration 30 [13055.538679] LustreError: 379175:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13055.545860] LustreError: 379176:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13055.554797] LustreError: 379175:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [13056.779633] Lustre: Mounted lustre-client [13056.785247] Lustre: Skipped 1 previous similar message [13057.922965] LustreError: 379285:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff7da01000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13057.991418] Lustre: Unmounted lustre-client [13060.442152] Key type lgssc unregistered [13060.681611] LNet: 379535:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13060.687721] LNetError: 379535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13060.703479] LNet: Removed LNI 192.168.206.51@tcp [13061.270335] Key type .llcrypt unregistered [13061.272981] Key type ._llcrypt unregistered [13061.903066] Key type ._llcrypt registered [13061.915238] Key type .llcrypt registered [13062.254753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13062.264107] alg: No test for adler32 (adler32-zlib) [13063.386396] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13063.588577] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13065.264406] Key type lgssc registered [13066.574462] Lustre: Echo OBD driver; http://www.lustre.org/ [13077.406430] Lustre: DEBUG MARKER: Iteration 31 [13077.726744] LustreError: 380369:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13077.727994] LustreError: 380370:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13077.740597] LustreError: 380369:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [13078.985997] Lustre: Mounted lustre-client [13080.567416] LustreError: 380475:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13080.672608] Lustre: Unmounted lustre-client [13083.215343] Key type lgssc unregistered [13083.612060] LNet: 380726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13083.622292] LNetError: 380726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13083.639473] LNet: Removed LNI 192.168.206.51@tcp [13084.565174] Key type .llcrypt unregistered [13084.568779] Key type ._llcrypt unregistered [13085.446695] Key type ._llcrypt registered [13085.455745] Key type .llcrypt registered [13085.656116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13085.674700] alg: No test for adler32 (adler32-zlib) [13086.777232] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13086.921071] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13088.536199] Key type lgssc registered [13089.983399] Lustre: Echo OBD driver; http://www.lustre.org/ [13102.097528] Lustre: DEBUG MARKER: Iteration 32 [13102.429341] LustreError: 381558:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13102.432679] LustreError: 381564:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13102.440057] LustreError: 381558:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [13103.682972] Lustre: Mounted lustre-client [13104.953725] LustreError: 381668:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a5d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13105.035856] Lustre: Unmounted lustre-client [13107.567592] Key type lgssc unregistered [13107.787984] LNet: 381919:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13107.796897] LNetError: 381919:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13107.817396] LNet: Removed LNI 192.168.206.51@tcp [13108.414395] Key type .llcrypt unregistered [13108.418994] Key type ._llcrypt unregistered [13109.107825] Key type ._llcrypt registered [13109.112825] Key type .llcrypt registered [13109.361597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13109.373383] alg: No test for adler32 (adler32-zlib) [13110.469910] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13110.712251] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13112.400241] Key type lgssc registered [13113.535205] Lustre: Echo OBD driver; http://www.lustre.org/ [13124.018456] Lustre: DEBUG MARKER: Iteration 33 [13124.319247] LustreError: 382753:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13124.322111] LustreError: 382752:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13124.342308] LustreError: 382753:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [13125.611112] Lustre: Mounted lustre-client [13127.116061] LustreError: 382861:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13127.232592] Lustre: Unmounted lustre-client [13129.779559] Key type lgssc unregistered [13130.040449] LNet: 383111:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13130.051890] LNetError: 383111:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13130.072053] LNet: Removed LNI 192.168.206.51@tcp [13130.772597] Key type .llcrypt unregistered [13130.775358] Key type ._llcrypt unregistered [13131.684238] Key type ._llcrypt registered [13131.712668] Key type .llcrypt registered [13131.998986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13132.010685] alg: No test for adler32 (adler32-zlib) [13133.186895] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13133.563925] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13135.312194] Key type lgssc registered [13136.361597] Lustre: Echo OBD driver; http://www.lustre.org/ [13147.949647] Lustre: DEBUG MARKER: Iteration 34 [13148.424671] LustreError: 383945:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13148.433294] LustreError: 383946:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13148.442262] LustreError: 383945:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13149.713959] Lustre: Mounted lustre-client [13149.723434] Lustre: Skipped 1 previous similar message [13150.803603] LustreError: 384050:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48cb7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13150.864863] Lustre: Unmounted lustre-client [13153.454649] Key type lgssc unregistered [13153.760552] LNet: 384301:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13153.770373] LNetError: 384301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13153.784827] LNet: Removed LNI 192.168.206.51@tcp [13154.435162] Key type .llcrypt unregistered [13154.439282] Key type ._llcrypt unregistered [13155.366461] Key type ._llcrypt registered [13155.369038] Key type .llcrypt registered [13155.705496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13155.718229] alg: No test for adler32 (adler32-zlib) [13156.832782] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13157.082376] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13158.816546] Key type lgssc registered [13160.243403] Lustre: Echo OBD driver; http://www.lustre.org/ [13174.038866] Lustre: DEBUG MARKER: Iteration 35 [13174.564822] LustreError: 385135:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13174.565227] LustreError: 385136:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13174.584710] LustreError: 385135:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13176.012384] Lustre: Mounted lustre-client [13177.974850] LustreError: 385246:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48ba6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13178.103298] Lustre: Unmounted lustre-client [13181.049807] Key type lgssc unregistered [13181.368725] LNet: 385497:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13181.383934] LNetError: 385497:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13181.403580] LNet: Removed LNI 192.168.206.51@tcp [13182.242154] Key type .llcrypt unregistered [13182.245848] Key type ._llcrypt unregistered [13183.407367] Key type ._llcrypt registered [13183.426643] Key type .llcrypt registered [13183.725456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13183.735283] alg: No test for adler32 (adler32-zlib) [13184.938291] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13185.292283] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13187.048231] Key type lgssc registered [13188.283402] Lustre: Echo OBD driver; http://www.lustre.org/ [13199.530679] Lustre: DEBUG MARKER: Iteration 36 [13200.032324] LustreError: 386330:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13200.035733] LustreError: 386331:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13200.056202] LustreError: 386330:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [13201.431606] Lustre: Mounted lustre-client [13202.997887] LustreError: 386440:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff43907000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13203.311860] Lustre: Unmounted lustre-client [13205.956644] Key type lgssc unregistered [13206.261849] LNet: 386690:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13206.275148] LNetError: 386690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13206.288529] LNet: Removed LNI 192.168.206.51@tcp [13207.114825] Key type .llcrypt unregistered [13207.126076] Key type ._llcrypt unregistered [13207.917036] Key type ._llcrypt registered [13207.919250] Key type .llcrypt registered [13208.393365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13208.420113] alg: No test for adler32 (adler32-zlib) [13209.459808] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13209.639291] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13211.296188] Key type lgssc registered [13212.701594] Lustre: Echo OBD driver; http://www.lustre.org/ [13224.396578] Lustre: DEBUG MARKER: Iteration 37 [13224.674032] LustreError: 387523:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13224.674502] LustreError: 387530:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13224.687184] LustreError: 387523:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13225.948494] Lustre: Mounted lustre-client [13227.252217] LustreError: 387641:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5060d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13227.464766] Lustre: Unmounted lustre-client [13229.758246] Key type lgssc unregistered [13229.975294] LNet: 387885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13229.981384] LNetError: 387885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13230.003391] LNet: Removed LNI 192.168.206.51@tcp [13230.622166] Key type .llcrypt unregistered [13230.624976] Key type ._llcrypt unregistered [13231.386519] Key type ._llcrypt registered [13231.391540] Key type .llcrypt registered [13231.745407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13231.759582] alg: No test for adler32 (adler32-zlib) [13232.823854] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13233.015638] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13234.672256] Key type lgssc registered [13235.487407] Lustre: Echo OBD driver; http://www.lustre.org/ [13243.259816] Lustre: DEBUG MARKER: Iteration 38 [13243.480337] LustreError: 388719:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13243.486489] LustreError: 388718:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13243.490226] LustreError: 388719:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [13244.678729] Lustre: Mounted lustre-client [13246.079402] LustreError: 388824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff815c1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13246.143299] Lustre: Unmounted lustre-client [13248.692838] Key type lgssc unregistered [13248.904809] LNet: 389073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13248.912164] LNetError: 389073:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13248.924938] LNet: Removed LNI 192.168.206.51@tcp [13249.584354] Key type .llcrypt unregistered [13249.586239] Key type ._llcrypt unregistered [13250.376323] Key type ._llcrypt registered [13250.384379] Key type .llcrypt registered [13250.719566] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13250.731487] alg: No test for adler32 (adler32-zlib) [13251.726113] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13251.939687] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13253.624181] Key type lgssc registered [13255.065510] Lustre: Echo OBD driver; http://www.lustre.org/ [13266.550202] Lustre: DEBUG MARKER: Iteration 39 [13267.155498] LustreError: 389907:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13267.156474] LustreError: 389906:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13267.175614] LustreError: 389907:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [13268.601955] Lustre: Mounted lustre-client [13270.338528] LustreError: 390015:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff8166a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13270.516151] Lustre: Unmounted lustre-client [13273.957683] Key type lgssc unregistered [13274.372995] LNet: 390265:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13274.390636] LNetError: 390265:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13274.429447] LNet: Removed LNI 192.168.206.51@tcp [13275.663496] Key type .llcrypt unregistered [13275.668371] Key type ._llcrypt unregistered [13276.580334] Key type ._llcrypt registered [13276.606159] Key type .llcrypt registered [13276.859203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13276.871445] alg: No test for adler32 (adler32-zlib) [13278.000303] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13278.328939] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13280.040801] Key type lgssc registered [13281.207530] Lustre: Echo OBD driver; http://www.lustre.org/ [13292.077402] Lustre: DEBUG MARKER: Iteration 40 [13292.602257] LustreError: 391101:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13292.609311] LustreError: 391102:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13292.621977] LustreError: 391101:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13293.951551] Lustre: Mounted lustre-client [13295.524464] LustreError: 391212:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff46541000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13295.660237] Lustre: Unmounted lustre-client [13298.217047] Key type lgssc unregistered [13298.506673] LNet: 391462:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13298.513433] LNetError: 391462:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13298.537131] LNet: Removed LNI 192.168.206.51@tcp [13299.313290] Key type .llcrypt unregistered [13299.317211] Key type ._llcrypt unregistered [13300.253692] Key type ._llcrypt registered [13300.256778] Key type .llcrypt registered [13300.472738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13300.480656] alg: No test for adler32 (adler32-zlib) [13301.568700] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13301.891348] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13303.544176] Key type lgssc registered [13305.301440] Lustre: Echo OBD driver; http://www.lustre.org/ [13319.691442] Lustre: DEBUG MARKER: Iteration 41 [13320.054183] LustreError: 392296:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13320.057164] LustreError: 392297:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13320.082860] LustreError: 392296:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4975 [13322.320399] Lustre: Mounted lustre-client [13323.694106] LustreError: 392401:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff48ded800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13323.815847] Lustre: Unmounted lustre-client [13326.346849] Key type lgssc unregistered [13326.640967] LNet: 392651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13326.655225] LNetError: 392651:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13327.737835] LNet: Removed LNI 192.168.206.51@tcp [13328.655149] Key type .llcrypt unregistered [13328.657624] Key type ._llcrypt unregistered [13329.593265] Key type ._llcrypt registered [13329.595394] Key type .llcrypt registered [13330.041342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13330.061773] alg: No test for adler32 (adler32-zlib) [13331.123819] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13331.297315] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13332.913853] Key type lgssc registered [13334.430774] Lustre: Echo OBD driver; http://www.lustre.org/ [13347.172831] Lustre: DEBUG MARKER: Iteration 42 [13347.609398] LustreError: 393486:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13347.609439] LustreError: 393487:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13347.627515] LustreError: 393486:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [13348.980200] Lustre: Mounted lustre-client [13350.314227] LustreError: 393592:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff58c8a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13350.422116] Lustre: Unmounted lustre-client [13353.154197] Key type lgssc unregistered [13353.419916] LNet: 393843:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13353.425389] LNetError: 393843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13353.444156] LNet: Removed LNI 192.168.206.51@tcp [13354.077284] Key type .llcrypt unregistered [13354.079534] Key type ._llcrypt unregistered [13354.610610] Key type ._llcrypt registered [13354.612164] Key type .llcrypt registered [13355.169917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13355.193267] alg: No test for adler32 (adler32-zlib) [13356.440428] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13356.913992] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13358.688569] Key type lgssc registered [13359.964544] Lustre: Echo OBD driver; http://www.lustre.org/ [13371.174642] Lustre: DEBUG MARKER: Iteration 43 [13371.521052] LustreError: 394676:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13371.522893] LustreError: 394678:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13371.553105] LustreError: 394676:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [13372.944538] Lustre: Mounted lustre-client [13374.735745] LustreError: 394787:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff50609800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13374.821157] Lustre: Unmounted lustre-client [13376.880390] Key type lgssc unregistered [13377.067910] LNet: 395033:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13377.079718] LNetError: 395033:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13377.103307] LNet: Removed LNI 192.168.206.51@tcp [13377.618171] Key type .llcrypt unregistered [13377.620481] Key type ._llcrypt unregistered [13378.415467] Key type ._llcrypt registered [13378.427820] Key type .llcrypt registered [13378.589283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13378.604870] alg: No test for adler32 (adler32-zlib) [13379.862136] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13380.104472] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13381.744175] Key type lgssc registered [13383.125123] Lustre: Echo OBD driver; http://www.lustre.org/ [13395.984451] Lustre: DEBUG MARKER: Iteration 44 [13396.453893] LustreError: 395866:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13396.460126] LustreError: 395873:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13396.473875] LustreError: 395866:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [13397.919856] Lustre: Mounted lustre-client [13399.133725] LustreError: 395984:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff42c5b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13399.204886] Lustre: Unmounted lustre-client [13401.770760] Key type lgssc unregistered [13402.093176] LNet: 396228:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13402.102953] LNetError: 396228:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13402.129963] LNet: Removed LNI 192.168.206.51@tcp [13402.856167] Key type .llcrypt unregistered [13402.861097] Key type ._llcrypt unregistered [13403.802456] Key type ._llcrypt registered [13403.812925] Key type .llcrypt registered [13404.051185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13404.073825] alg: No test for adler32 (adler32-zlib) [13405.125951] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13405.335017] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13407.049338] Key type lgssc registered [13408.282513] Lustre: Echo OBD driver; http://www.lustre.org/ [13421.546320] Lustre: DEBUG MARKER: Iteration 45 [13422.002388] LustreError: 397062:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13422.009614] LustreError: 397063:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13422.027342] LustreError: 397062:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [13423.339612] Lustre: Mounted lustre-client [13425.239796] LustreError: 397172:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff75a9d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13425.430124] Lustre: Unmounted lustre-client [13428.278801] Key type lgssc unregistered [13428.631762] LNet: 397417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13428.636727] LNetError: 397417:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13428.662880] LNet: Removed LNI 192.168.206.51@tcp [13429.638949] Key type .llcrypt unregistered [13429.644371] Key type ._llcrypt unregistered [13430.889721] Key type ._llcrypt registered [13430.896904] Key type .llcrypt registered [13431.496303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13431.518971] alg: No test for adler32 (adler32-zlib) [13432.567459] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13432.738957] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13434.440160] Key type lgssc registered [13435.720182] Lustre: Echo OBD driver; http://www.lustre.org/ [13446.234527] Lustre: DEBUG MARKER: Iteration 46 [13446.559498] LustreError: 398251:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13446.559839] LustreError: 398252:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13446.570232] LustreError: 398251:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [13447.746169] Lustre: Mounted lustre-client [13450.348321] LustreError: 398358:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a71a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13450.414130] Lustre: Unmounted lustre-client [13452.552859] Key type lgssc unregistered [13452.725749] LNet: 398607:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13452.730841] LNetError: 398607:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13452.742089] LNet: Removed LNI 192.168.206.51@tcp [13453.138215] Key type .llcrypt unregistered [13453.139922] Key type ._llcrypt unregistered [13453.634235] Key type ._llcrypt registered [13453.645060] Key type .llcrypt registered [13453.799869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13453.812792] alg: No test for adler32 (adler32-zlib) [13454.746397] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13454.893480] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13456.568420] Key type lgssc registered [13457.645583] Lustre: Echo OBD driver; http://www.lustre.org/ [13472.601992] Lustre: DEBUG MARKER: Iteration 47 [13473.486260] LustreError: 399442:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13473.504315] LustreError: 399441:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13473.517494] LustreError: 399442:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [13475.072235] Lustre: Mounted lustre-client [13477.194867] LustreError: 399552:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a5d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13477.352780] Lustre: Unmounted lustre-client [13483.208534] Key type lgssc unregistered [13483.567719] LNet: 399802:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13483.585679] LNetError: 399802:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13483.613706] LNet: Removed LNI 192.168.206.51@tcp [13484.783427] Key type .llcrypt unregistered [13484.793495] Key type ._llcrypt unregistered [13486.439131] Key type ._llcrypt registered [13486.440641] Key type .llcrypt registered [13487.448664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13487.474848] alg: No test for adler32 (adler32-zlib) [13488.655266] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13488.971430] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13490.712182] Key type lgssc registered [13493.272553] Lustre: Echo OBD driver; http://www.lustre.org/ [13506.650469] Lustre: DEBUG MARKER: Iteration 48 [13506.952314] LustreError: 400649:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13506.963343] LustreError: 400650:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13506.982358] LustreError: 400649:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [13508.356771] Lustre: Mounted lustre-client [13510.106781] LustreError: 400758:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff4303d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13510.238580] Lustre: Unmounted lustre-client [13513.014226] Key type lgssc unregistered [13513.345912] LNet: 401008:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13513.357415] LNetError: 401008:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13513.387265] LNet: Removed LNI 192.168.206.51@tcp [13514.168156] Key type .llcrypt unregistered [13514.170976] Key type ._llcrypt unregistered [13515.089233] Key type ._llcrypt registered [13515.119533] Key type .llcrypt registered [13515.532632] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13515.542863] alg: No test for adler32 (adler32-zlib) [13516.933600] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13517.259868] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13519.088272] Key type lgssc registered [13520.374666] Lustre: Echo OBD driver; http://www.lustre.org/ [13530.808721] Lustre: DEBUG MARKER: Iteration 49 [13531.059336] LustreError: 401840:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13531.060069] LustreError: 401841:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13531.075378] LustreError: 401840:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [13532.308449] Lustre: Mounted lustre-client [13532.310237] Lustre: Skipped 1 previous similar message [13533.644911] LustreError: 401952:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a422800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13533.658492] LustreError: 401952:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [13533.752143] Lustre: Unmounted lustre-client [13535.725657] Key type lgssc unregistered [13535.944526] LNet: 402198:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13535.955086] LNetError: 402198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13535.979531] LNet: Removed LNI 192.168.206.51@tcp [13536.460175] Key type .llcrypt unregistered [13536.462428] Key type ._llcrypt unregistered [13537.012207] Key type ._llcrypt registered [13537.029618] Key type .llcrypt registered [13537.289214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13537.307848] alg: No test for adler32 (adler32-zlib) [13538.346342] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13538.528215] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13540.184186] Key type lgssc registered [13541.151586] Lustre: Echo OBD driver; http://www.lustre.org/ [13551.273519] Lustre: DEBUG MARKER: Iteration 50 [13551.624274] LustreError: 403029:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [13551.625106] LustreError: 403040:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [13551.645880] LustreError: 403029:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [13552.892767] Lustre: Mounted lustre-client [13554.143648] LustreError: 403142:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff5a5d4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13554.269124] Lustre: Unmounted lustre-client [13556.879687] Key type lgssc unregistered [13557.129153] LNet: 403392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13557.139238] LNetError: 403392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13557.161244] LNet: Removed LNI 192.168.206.51@tcp [13557.827140] Key type .llcrypt unregistered [13557.828895] Key type ._llcrypt unregistered [13558.617994] Key type ._llcrypt registered [13558.621596] Key type .llcrypt registered [13559.097115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13559.130082] alg: No test for adler32 (adler32-zlib) [13560.169527] Lustre: Lustre: Build Version: 2.17.50_143_gf28c0c4 [13560.439353] LNet: Added LNI 192.168.206.51@tcp [8/256/0/180] [13562.128660] Key type lgssc registered [13563.293180] Lustre: Echo OBD driver; http://www.lustre.org/ [13573.196429] Lustre: Mounted lustre-client [13579.491298] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 13:10:23 (1779988223) [13596.128167] Lustre: 404751:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988225/real 1779988225] req@ffff92ff7da87100 x1866452912057344/t0(0) o36->lustre-MDT0000-mdc-ffff92ff75696000@192.168.206.151@tcp:12/10 lens 496/440 e 0 to 1 dl 1779988241 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13596.148724] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13596.172680] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13612.512607] Lustre: 404751:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988241/real 1779988241] req@ffff92ff7da87100 x1866452912057344/t0(0) o36->lustre-MDT0000-mdc-ffff92ff75696000@192.168.206.151@tcp:12/10 lens 496/440 e 0 to 1 dl 1779988257 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13612.555162] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13612.619635] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13628.896221] Lustre: 404751:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988257/real 1779988257] req@ffff92ff7da87100 x1866452912057344/t0(0) o36->lustre-MDT0000-mdc-ffff92ff75696000@192.168.206.151@tcp:12/10 lens 496/440 e 0 to 1 dl 1779988273 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13628.925650] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13628.985356] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13645.280077] Lustre: 404751:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988274/real 1779988274] req@ffff92ff7da87100 x1866452912057344/t0(0) o36->lustre-MDT0000-mdc-ffff92ff75696000@192.168.206.151@tcp:12/10 lens 496/440 e 0 to 1 dl 1779988290 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13645.309268] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13645.354130] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13661.664238] Lustre: 404751:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988290/real 1779988290] req@ffff92ff7da87100 x1866452912057344/t0(0) o36->lustre-MDT0000-mdc-ffff92ff75696000@192.168.206.151@tcp:12/10 lens 496/440 e 0 to 1 dl 1779988306 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13661.706590] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection to lustre-MDT0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13661.786928] Lustre: lustre-MDT0000-mdc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13664.512673] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 13:11:47 (1779988307) [13676.944754] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 13:12:00 (1779988320) [13688.763512] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 13:12:12 (1779988332) [13696.915873] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 13:12:20 (1779988340) [13718.777701] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 13:12:42 (1779988362) [13753.453573] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 13:13:17 (1779988397) [13759.597392] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 13:13:23 (1779988403) [13759.862046] LustreError: 409536:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [13761.953342] LustreError: 409536:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [13767.406904] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 13:13:31 (1779988411) [13788.576153] Lustre: 403586:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988417/real 1779988417] req@ffff92ff7b762d80 x1866452913115520/t0(0) o4->lustre-OST0000-osc-ffff92ff75696000@192.168.206.151@tcp:6/4 lens 4584/448 e 0 to 1 dl 1779988433 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13788.605415] Lustre: lustre-OST0000-osc-ffff92ff75696000: Connection to lustre-OST0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13804.012785] Lustre: lustre-OST0000-osc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13820.384153] Lustre: 403587:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1779988449/real 1779988449] req@ffff92ff7c58f480 x1866452913115904/t0(0) o4->lustre-OST0000-osc-ffff92ff75696000@192.168.206.151@tcp:6/4 lens 4584/448 e 0 to 1 dl 1779988465 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13820.384501] Lustre: lustre-OST0000-osc-ffff92ff75696000: Connection to lustre-OST0000 (at 192.168.206.151@tcp) was lost; in progress operations using this service will wait for recovery to complete [13820.406732] Lustre: 403587:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 16 previous similar messages [13820.424621] Lustre: Skipped 1 previous similar message [13820.450103] Lustre: lustre-OST0000-osc-ffff92ff75696000: Connection restored to 192.168.206.151@tcp (at 192.168.206.151@tcp) [13866.991250] Lustre: DEBUG MARKER: oleg651-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff92ff75696000.ost_server_uuid 50 [13868.544749] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff92ff75696000.ost_server_uuid in FULL state after 0 sec [13870.465656] Lustre: DEBUG MARKER: cleanup: ====================================================== [13872.720724] Lustre: DEBUG MARKER: == sanityn test complete, duration 13598 sec ============= 13:15:15 (1779988515) [13874.779232] Lustre: DEBUG MARKER: === sanityn: start cleanup 13:15:18 (1779988518) === [14226.193786] LustreError: 411631:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff7aa97000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14226.246656] Lustre: Unmounted lustre-client [14230.814329] Lustre: DEBUG MARKER: === sanityn: finish cleanup 13:21:14 (1779988874) === [14233.632743] LustreError: 411939:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff92ff75696000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14233.646974] LustreError: 411939:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [14233.692204] Lustre: Unmounted lustre-client [14286.294370] Key type lgssc unregistered [14286.747479] LNet: 412625:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14286.755032] LNetError: 412625:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14287.788589] LNet: Removed LNI 192.168.206.51@tcp [14288.405255] Key type .llcrypt unregistered [14288.408994] Key type ._llcrypt unregistered