[ 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-8.fc42 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 502153743 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 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.003222] x2apic enabled [ 0.004000] Switched APIC routing to physical x2apic. [ 0.004022] kvm-guest: setup PV IPIs [ 0.007574] ..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.008034] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009030] pid_max: default: 32768 minimum: 301 [ 0.010163] LSM: Security Framework initializing [ 0.012072] Yama: becoming mindful. [ 0.013057] SELinux: Initializing. [ 0.014093] *** VALIDATE selinux *** [ 0.022632] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027625] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029138] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031148] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032146] *** VALIDATE tmpfs *** [ 0.033000] *** VALIDATE proc *** [ 0.033000] *** VALIDATE cgroup *** [ 0.033018] *** VALIDATE cgroup2 *** [ 0.035322] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036177] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037013] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038038] Spectre V2 : User space: Vulnerable [ 0.039015] Speculative Store Bypass: Vulnerable [ 0.042106] debug: unmapping init [mem 0xffffffffb7e59000-0xffffffffb7e60fff] [ 0.045296] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046767] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047032] ... version: 2 [ 0.048019] ... bit width: 48 [ 0.049021] ... generic registers: 4 [ 0.050018] ... value mask: 0000ffffffffffff [ 0.051023] ... max period: 00007fffffffffff [ 0.052021] ... fixed-purpose events: 3 [ 0.053018] ... event mask: 000000070000000f [ 0.055282] rcu: Hierarchical SRCU implementation. [ 0.057905] smp: Bringing up secondary CPUs ... [ 0.058623] x86: Booting SMP configuration: [ 0.059042] .... node #0, CPUs: #1 #2 #3 [ 0.063097] smp: Brought up 1 node, 4 CPUs [ 0.065022] smpboot: Max logical packages: 1 [ 0.066020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.164171] node 0 deferred pages initialised in 96ms [ 0.168308] devtmpfs: initialized [ 0.169348] x86/mm: Memory block size: 128MB [ 0.172594] gcov: version magic: 0x41383552 [ 0.175063] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.176103] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.177451] pinctrl core: initialized pinctrl subsystem [ 0.178609] [ 0.179013] ************************************************************* [ 0.180022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.181036] ** ** [ 0.182022] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.183018] ** ** [ 0.184020] ** This means that this kernel is built to expose internal ** [ 0.185017] ** IOMMU data structures, which may compromise security on ** [ 0.186019] ** your system. ** [ 0.187020] ** ** [ 0.188018] ** If you see this message and you are not debugging the ** [ 0.189121] ** kernel, report this immediately to your vendor! ** [ 0.190020] ** ** [ 0.191019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.192079] ************************************************************* [ 0.194000] NET: Registered protocol family 16 [ 0.196721] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.200077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.203165] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.207116] cpuidle: using governor menu [ 0.210194] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.213804] PCI: Using configuration type 1 for base access [ 0.216161] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.225066] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.227022] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.231186] cryptd: max_cpu_qlen set to 1000 [ 0.234258] ACPI: Added _OSI(Module Device) [ 0.235014] ACPI: Added _OSI(Processor Device) [ 0.237015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.238067] ACPI: Added _OSI(Processor Aggregator Device) [ 0.244844] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.252061] ACPI: Interpreter enabled [ 0.253157] ACPI: PM: (supports S0 S3 S4 S5) [ 0.255016] ACPI: Using IOAPIC for interrupt routing [ 0.257206] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.261502] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.273844] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.276062] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.279024] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.283114] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.289617] acpiphp: Slot [2] registered [ 0.291181] acpiphp: Slot [5] registered [ 0.293184] acpiphp: Slot [6] registered [ 0.294420] acpiphp: Slot [3] registered [ 0.296245] acpiphp: Slot [4] registered [ 0.298083] acpiphp: Slot [7] registered [ 0.299318] acpiphp: Slot [8] registered [ 0.301127] acpiphp: Slot [9] registered [ 0.303144] acpiphp: Slot [10] registered [ 0.304189] acpiphp: Slot [11] registered [ 0.306305] acpiphp: Slot [12] registered [ 0.308138] acpiphp: Slot [13] registered [ 0.310179] acpiphp: Slot [14] registered [ 0.312182] acpiphp: Slot [15] registered [ 0.314164] acpiphp: Slot [16] registered [ 0.316147] acpiphp: Slot [17] registered [ 0.318142] acpiphp: Slot [18] registered [ 0.319139] acpiphp: Slot [19] registered [ 0.322146] acpiphp: Slot [20] registered [ 0.323261] acpiphp: Slot [21] registered [ 0.325248] acpiphp: Slot [22] registered [ 0.327191] acpiphp: Slot [23] registered [ 0.329226] acpiphp: Slot [24] registered [ 0.331119] acpiphp: Slot [25] registered [ 0.333140] acpiphp: Slot [26] registered [ 0.335216] acpiphp: Slot [27] registered [ 0.337122] acpiphp: Slot [28] registered [ 0.339093] acpiphp: Slot [29] registered [ 0.341173] acpiphp: Slot [30] registered [ 0.343198] acpiphp: Slot [31] registered [ 0.345162] PCI host bridge to bus 0000:00 [ 0.347031] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.349060] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.352030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.354032] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.357152] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.361078] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.363217] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.366442] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.370533] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.379020] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.384477] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.387023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.390026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.392028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.395491] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.398890] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.401051] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.404997] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.410017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.421020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.426019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.432134] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.447019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.458169] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.476078] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.489451] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.498026] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.507029] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.529021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.542772] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.544648] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.546472] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.549358] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.551246] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.557044] iommu: Default domain type: Passthrough [ 0.558200] SCSI subsystem initialized [ 0.560169] ACPI: bus type USB registered [ 0.561125] usbcore: registered new interface driver usbfs [ 0.564177] usbcore: registered new interface driver hub [ 0.566144] usbcore: registered new device driver usb [ 0.567248] pps_core: LinuxPPS API ver. 1 registered [ 0.569017] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.571209] PTP clock support registered [ 0.574072] EDAC MC: Ver: 3.0.0 [ 0.575522] PCI: Using ACPI for IRQ routing [ 0.578032] NetLabel: Initializing [ 0.579009] NetLabel: domain hash size = 128 [ 0.580010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.581124] NetLabel: unlabeled traffic allowed by default [ 0.583567] vgaarb: loaded [ 0.586009] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.588014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.595144] clocksource: Switched to clocksource kvm-clock [ 0.714333] VFS: Disk quotas dquot_6.6.0 [ 0.715856] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.717953] *** VALIDATE ramfs *** [ 0.718996] *** VALIDATE hugetlbfs *** [ 0.720459] pnp: PnP ACPI init [ 0.722829] pnp: PnP ACPI: found 6 devices [ 0.738638] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.741820] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.743791] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.746796] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.750168] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.753490] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.756121] NET: Registered protocol family 2 [ 0.758699] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.764139] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.768326] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.775508] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.778844] TCP: Hash tables configured (established 65536 bind 65536) [ 0.781541] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.784623] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.786992] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.789724] NET: Registered protocol family 1 [ 0.793475] RPC: Registered named UNIX socket transport module. [ 0.795286] RPC: Registered udp transport module. [ 0.796730] RPC: Registered tcp transport module. [ 0.798060] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.799995] NET: Registered protocol family 44 [ 0.801438] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.803065] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.804731] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.806417] PCI: CLS 0 bytes, default 64 [ 0.807745] Unpacking initramfs... [ 2.545289] debug: unmapping init [mem 0xffff97d5bcc64000-0xffff97d5bffcffff] [ 2.547964] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.549481] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.551955] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.106184] Initialise system trusted keyrings [ 3.107977] Key type blacklist registered [ 3.110088] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.120585] zbud: loaded [ 3.123917] *** VALIDATE nfs *** [ 3.125033] *** VALIDATE nfs4 *** [ 3.126872] pstore: using deflate compression [ 3.130898] Platform Keyring initialized [ 3.258673] NET: Registered protocol family 38 [ 3.260840] Key type asymmetric registered [ 3.262586] Asymmetric key parser 'x509' registered [ 3.264738] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.270815] io scheduler mq-deadline registered [ 3.272716] io scheduler kyber registered [ 3.276900] io scheduler bfq registered [ 3.279300] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.285822] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.289263] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.293303] ACPI: Power Button [PWRF] [ 3.299657] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.307577] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.316865] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.345387] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.374553] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.379634] Non-volatile memory driver v1.3 [ 3.381463] Linux agpgart interface v0.103 [ 3.411648] virtio_blk virtio1: [vda] 134712 512-byte logical blocks (69.0 MB/65.8 MiB) [ 3.413976] vda: detected capacity change from 0 to 68972544 [ 3.437747] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.440745] vdb: detected capacity change from 0 to 1073741824 [ 3.460808] libphy: Fixed MDIO Bus: probed [ 3.467095] usbcore: registered new interface driver usbserial_generic [ 3.468920] usbserial: USB Serial support registered for generic [ 3.470908] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.474852] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.476933] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.480633] mousedev: PS/2 mouse device common for all mice [ 3.484451] rtc_cmos 00:05: RTC can wake from S4 [ 3.488859] rtc_cmos 00:05: registered as rtc0 [ 3.493052] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.497162] intel_pstate: CPU model not supported [ 3.497679] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.503534] hid: raw HID events driver (C) Jiri Kosina [ 3.508885] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.511129] usbcore: registered new interface driver usbhid [ 3.511137] usbhid: USB HID core driver [ 3.511420] drop_monitor: Initializing network drop monitor service [ 3.511554] Initializing XFRM netlink socket [ 3.511906] NET: Registered protocol family 10 [ 3.517939] Segment Routing with IPv6 [ 3.522752] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.537263] NET: Registered protocol family 17 [ 3.539757] mpls_gso: MPLS GSO support [ 3.546810] RAS: Correctable Errors collector initialized. [ 3.549449] AVX version of gcm_enc/dec engaged. [ 3.551282] AES CTR mode by8 optimization enabled [ 3.807583] sched_clock: Marking stable (3807416298, 0)->(4819740689, -1012324391) [ 3.815324] registered taskstats version 1 [ 3.818763] Loading compiled-in X.509 certificates [ 3.824485] zswap: loaded using pool lzo/zbud [ 3.999558] Key type big_key registered [ 4.024335] Key type encrypted registered [ 4.025681] ima: No TPM chip found, activating TPM-bypass! [ 4.028685] ima: Allocated hash algorithm: sha1 [ 4.030718] ima: No architecture policies found [ 4.033474] evm: Initialising EVM extended attributes: [ 4.035885] evm: security.selinux [ 4.037093] evm: security.ima [ 4.038531] evm: security.capability [ 4.039720] evm: HMAC attrs: 0x1 [ 4.042192] rtc_cmos 00:05: setting system clock to 2026-03-02 16:38:19 UTC (1772469499) [ 4.050432] debug: unmapping init [mem 0xffffffffb8e03000-0xffffffffb8ffffff] [ 4.053953] debug: unmapping init [mem 0xffffffffb7b82000-0xffffffffb7e58fff] [ 4.063246] Write protecting the kernel read-only data: 28672k [ 4.068392] debug: unmapping init [mem 0xffffffffb6203000-0xffffffffb63fffff] [ 4.072068] debug: unmapping init [mem 0xffffffffb6b14000-0xffffffffb6bfffff] [ 4.124626] 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) [ 4.138919] systemd[1]: Detected virtualization kvm. [ 4.141809] systemd[1]: Detected architecture x86-64. [ 4.144445] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.180949] systemd[1]: No hostname configured. [ 4.183284] systemd[1]: Set hostname to . [ 4.186409] random: systemd: uninitialized urandom read (16 bytes read) [ 4.191625] systemd[1]: Initializing machine ID from random generator. [ 4.328834] random: ln: uninitialized urandom read (6 bytes read) [ 4.524529] random: systemd: uninitialized urandom read (16 bytes read) [ 4.528435] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.543782] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.554515] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.721971] device-mapper: uevent: version 1.0.3 [ 5.724434] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook...[ 6.801586] random: fast init done [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.917397] virtio_net virtio0 ens2: renamed from eth0 [ 7.118444] scsi host0: ata_piix [ 7.491482] scsi host1: ata_piix [ 7.493824] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.499216] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.485837] random: crng init done [ 13.487879] random: 7 urandom warning(s) missed due to ratelimiting [ 16.186636] dracut-initqueue[585]: RTNETLINK answers: File exists 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. [ 17.979747] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.434505] printk: systemd: 26 output lines suppressed due to ratelimiting [ 20.907222] SELinux: Disabled at runtime. [ 20.970621] 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) [ 20.977920] systemd[1]: Detected virtualization kvm. [ 20.979573] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.644426] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.648830] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.665034] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.675505] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.679562] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.691282] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.696118] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice User and Session Slice. [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Debug File System... Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Slices. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. Starting udev Coldplug all Devices... [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ 23.235796] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 24.280154] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 25.770127] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.323213] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 28.852828] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.279336] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (10s / no limit)[ 33.628902] Key type dns_resolver registered [ 34.046452] NFS: Registering the id_resolver key type [ 34.048655] Key type id_resolver registered [ 34.050406] Key type id_legacy registered [ **] A start job is running for Configur…only root support (11s / no limit) [ *] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started dnf makecache --timer. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg216-client login: [ 109.505507] libcfs: loading out-of-tree module taints kernel. [ 109.665902] Key type ._llcrypt registered [ 109.668547] Key type .llcrypt registered [ 110.447807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 110.469840] alg: No test for adler32 (adler32-zlib) [ 112.259282] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 113.372628] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 115.272226] Key type lgssc registered [ 118.453655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 119.794224] hrtimer: interrupt took 3731069 ns [ 318.871000] Lustre: Mounted lustre-client [ 324.035239] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 342.482473] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing check_logdir /tmp/testlogs/ [ 344.547184] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 24s idle [ 349.584765] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing yml_node [ 356.108512] Lustre: DEBUG MARKER: Client: 2.17.50.194 [ 359.234314] Lustre: DEBUG MARKER: MDS: 2.17.50.194 [ 362.512771] Lustre: DEBUG MARKER: OSS: 2.17.50.194 [ 364.969464] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Mar 2 11:44:18 EST 2026 [ 388.180502] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 390.139984] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 392.390417] Lustre: DEBUG MARKER: === sanityn: start setup 11:44:46 (1772469886) === [ 393.896345] Lustre: Mounted lustre-client [ 399.105798] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing check_config_client /mnt/lustre [ 424.193104] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 440.035203] Lustre: DEBUG MARKER: === sanityn: finish setup 11:45:34 (1772469934) === [ 442.428974] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 11:45:36 (1772469936) [ 452.184233] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 11:45:46 (1772469946) [ 460.768559] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 20s idle [ 460.779878] Lustre: Skipped 1 previous similar message [ 460.821928] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 11:45:54 (1772469954) [ 470.298713] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 11:46:03 (1772469963) [ 479.639329] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 11:46:13 (1772469973) [ 488.060954] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 11:46:21 (1772469981) [ 498.152528] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 11:46:31 (1772469991) [ 507.001209] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 11:46:40 (1772470000) [ 517.009755] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 11:46:50 (1772470010) [ 524.832524] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 11:46:58 (1772470018) [ 533.141486] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 11:47:06 (1772470026) [ 537.570368] Lustre: lustre-OST0001-osc-ffff97d607985000: disconnect after 20s idle [ 542.698068] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 11:47:16 (1772470036) [ 550.515059] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 11:47:24 (1772470044) [ 558.049969] Lustre: lustre-OST0000-osc-ffff97d61279f000: disconnect after 24s idle [ 559.691903] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 11:47:33 (1772470053) [ 568.793968] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 11:47:42 (1772470062) [ 577.350847] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 11:47:51 (1772470071) [ 578.529075] Lustre: lustre-OST0001-osc-ffff97d607985000: disconnect after 22s idle [ 585.942784] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 11:47:59 (1772470079) [ 595.959368] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 11:48:09 (1772470089) [ 606.792663] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 11:48:20 (1772470100) [ 614.951950] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 11:48:28 (1772470108) [ 623.065260] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 11:48:37 (1772470117) [ 623.943465] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 754.704916] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 11:50:48 (1772470248) [ 765.362846] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 11:50:58 (1772470258) [ 774.452441] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 11:51:08 (1772470268) [ 782.614457] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 11:51:16 (1772470276) [ 790.765449] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 11:51:24 (1772470284) [ 798.881664] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 11:51:32 (1772470292) [ 801.856430] Lustre: DEBUG MARKER: chmod [ 809.192737] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 11:51:43 (1772470303) [ 1966.055971] Lustre: lustre-OST0000-osc-ffff97d61279f000: disconnect after 21s idle [ 1966.630588] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 12:11:00 (1772471460) [ 2129.889476] Lustre: lustre-OST0000-osc-ffff97d61279f000: disconnect after 23s idle [ 2129.902860] Lustre: Skipped 1 previous similar message [ 2204.806837] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 12:14:58 (1772471698) [ 2321.947161] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 12:16:56 (1772471816) [ 2468.474661] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 12:19:22 (1772471962) [ 2513.892488] Lustre: lustre-OST0001-osc-ffff97d61279f000: disconnect after 24s idle [ 2514.758605] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 12:20:08 (1772472008) [ 2522.799168] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 12:20:16 (1772472016) [ 2524.670330] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2524.765052] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2524.891477] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.018098] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.143952] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.292930] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.414778] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.526530] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.599560] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.664634] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.720304] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.784644] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.849184] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.914580] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2525.978728] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.056336] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.132646] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.244300] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.335759] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.410926] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.483857] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.555162] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.627809] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.743709] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.834043] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2526.918616] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.002912] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.064561] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.150903] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.220026] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.313876] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.421052] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.514565] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.595809] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.683106] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.742454] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.828604] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.911938] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2527.992816] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.064699] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.125431] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.200472] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.268935] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.358140] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.454794] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.532483] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.604687] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.677243] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.772240] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.855872] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.922865] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2528.974331] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.066098] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.144497] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.216285] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.288730] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.356504] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.436867] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.515155] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.586935] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.651253] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.725340] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.790879] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2529.888529] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.001448] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.117579] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.214253] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.322930] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.455840] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.575959] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.665174] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.759971] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.833269] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2530.978880] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.085237] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.193757] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.386631] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.531733] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.666486] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.825436] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2531.953060] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.099917] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.232150] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.346699] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.429304] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.547306] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.635458] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.760870] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.881773] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2532.977495] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.041646] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.148572] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.221688] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.300482] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.425717] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.561687] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.685670] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.862303] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.922425] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2533.990805] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.060883] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.109652] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.165712] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.245379] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.345723] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.436613] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.502563] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.583592] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.643399] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.728488] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.816683] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.891150] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2534.983544] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.120459] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.235497] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.347196] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.429874] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.519475] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.602103] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.675525] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.758994] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.841466] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.902246] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2535.979133] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.060262] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.135451] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.206976] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.286426] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.356471] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.427833] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.514349] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.591140] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.668133] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.728435] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.816153] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.896587] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2536.973324] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.042256] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.135219] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.241247] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.320563] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.400191] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.464597] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.555162] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.634992] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.726534] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.809778] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.894235] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2537.977682] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.057188] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.123520] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.192346] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.265170] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.348142] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.430449] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.499452] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.573784] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.645940] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.728445] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.817327] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.890471] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.944254] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2538.998416] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.089728] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.176447] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.241429] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.312083] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.387136] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.476279] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.491088] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 21s idle [ 2539.591465] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.693393] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.752899] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.829487] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.918591] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2539.992441] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.090826] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.215357] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.302559] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.378760] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.452679] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.551634] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.630228] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.716537] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.787230] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.850506] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.911267] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2540.998545] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.090247] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.195707] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.295504] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.401455] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.489638] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.584051] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.672413] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.768065] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.857550] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2541.945736] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.033801] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.110669] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.183742] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.251909] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.321631] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.404285] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.485492] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.554889] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.640439] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.730522] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.806393] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.878250] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2542.964508] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2543.037608] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2543.103648] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2543.184358] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2543.300961] rw_seq_cst_vs_d (32385): drop_caches: 3 [ 2552.450508] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 12:20:46 (1772472046) [ 2552.965590] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.184320] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.282252] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.340486] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.575713] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.639381] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.789340] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2553.973198] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.080597] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.174135] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.417124] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.470633] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.605411] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.711717] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.825954] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2554.992805] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.252542] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.343778] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.586983] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.662651] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.706643] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2555.873917] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.081551] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.235453] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.329161] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.376520] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.469796] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.543649] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.624563] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.817482] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2556.957504] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.070088] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.244855] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.389900] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.426046] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.483843] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.562094] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.694228] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.791337] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.853548] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.901733] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2557.997591] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.254704] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.373242] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.506975] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.555542] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.661483] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2558.922452] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.278000] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.451299] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.597629] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.706442] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.757817] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.840764] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.929976] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2559.993571] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.118482] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.461626] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.617251] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.700910] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.823687] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2560.983515] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.103050] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.162500] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.304328] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.370332] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.453855] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.534261] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.577962] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.702414] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2561.861432] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.026932] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.376219] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.426555] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.515215] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.719217] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2562.926395] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.194486] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.316929] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.453474] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.633435] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.787281] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.869453] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.924763] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2563.998054] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.119453] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.453334] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.651319] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.682190] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.775416] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.866634] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2564.998942] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.090381] Lustre: lustre-OST0001-osc-ffff97d61279f000: disconnect after 22s idle [ 2565.097129] Lustre: Skipped 1 previous similar message [ 2565.144498] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.374691] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.522685] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.658066] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.778369] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.832643] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2565.951529] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.223490] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.292307] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.369620] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.464214] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.558427] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.641222] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.746743] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2566.891975] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.064242] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.151150] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.187594] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.614954] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.702985] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.777624] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2567.843954] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.036519] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.083543] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.201849] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.533861] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.673420] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.747882] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.828278] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2568.915354] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.044797] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.142058] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.226355] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.473868] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.538744] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.665989] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.800699] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2569.917277] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.077193] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.109721] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.528369] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.632055] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.737514] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.796118] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.866215] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2570.965889] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.012889] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.126157] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.228362] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.354674] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.492202] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.629862] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.781188] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.918430] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2571.964775] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2572.038208] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2572.267900] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2572.339376] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2572.545304] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2572.723849] rw_seq_cst_vs_d (32969): drop_caches: 3 [ 2581.747789] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 12:21:15 (1772472075) [ 2590.243557] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 12:21:24 (1772472084) [ 2598.358971] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 12:21:32 (1772472092) [ 2630.279447] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 12:22:04 (1772472124) [ 2630.758882] bash (35451): drop_caches: 3 [ 2634.095591] bash (35451): drop_caches: 3 [ 2637.317251] bash (35451): drop_caches: 3 [ 2640.519510] bash (35451): drop_caches: 3 [ 2643.723788] bash (35451): drop_caches: 3 [ 2646.937717] bash (35451): drop_caches: 3 [ 2650.161264] bash (35451): drop_caches: 3 [ 2653.326679] bash (35451): drop_caches: 3 [ 2656.502795] bash (35451): drop_caches: 3 [ 2659.669735] bash (35451): drop_caches: 3 [ 2663.226754] bash (35451): drop_caches: 3 [ 2666.392649] bash (35451): drop_caches: 3 [ 2669.922442] bash (35451): drop_caches: 3 [ 2673.084173] bash (35451): drop_caches: 3 [ 2676.224556] bash (35451): drop_caches: 3 [ 2679.446917] bash (35451): drop_caches: 3 [ 2682.696226] bash (35451): drop_caches: 3 [ 2688.287087] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 12:23:01 (1772472181) [ 2701.182746] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 12:23:15 (1772472195) [ 2727.687212] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 12:23:41 (1772472221) [ 2736.609438] Lustre: DEBUG MARKER: loop 5 [ 2739.168404] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 21s idle [ 2739.188130] Lustre: Skipped 1 previous similar message [ 2742.393711] Lustre: DEBUG MARKER: loop 10 [ 2748.259440] Lustre: DEBUG MARKER: loop 15 [ 2753.882274] Lustre: DEBUG MARKER: loop 20 [ 2763.316982] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 12:24:17 (1772472257) [ 2770.237412] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 12:24:24 (1772472264) [ 2778.277972] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 12:24:31 (1772472271) [ 2785.250130] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 21s idle [ 2785.257576] Lustre: Skipped 1 previous similar message [ 2848.399481] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 12:25:42 (1772472342) [ 2854.513310] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 12:25:48 (1772472348) [ 2860.753436] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 12:25:54 (1772472354) [ 2867.784158] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 12:26:02 (1772472362) [ 2874.533039] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 12:26:08 (1772472368) [ 2881.510935] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 12:26:15 (1772472375) [ 2889.631136] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 12:26:23 (1772472383) [ 2897.925585] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2899.898610] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 12:26:33 (1772472393) [ 2908.562394] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 12:26:42 (1772472402) [ 2909.348659] Lustre: *** cfs_fail_loc=314, val=0*** [ 2910.368307] Lustre: *** cfs_fail_loc=314, val=0*** [ 2910.384745] Lustre: Skipped 2 previous similar messages [ 2913.251139] Lustre: lustre-OST0001-osc-ffff97d607985000: disconnect after 22s idle [ 2913.267712] Lustre: Skipped 3 previous similar messages [ 2918.096392] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 12:26:51 (1772472411) [ 2928.096089] Lustre: *** cfs_fail_loc=314, val=0*** [ 2933.739288] Lustre: lustre-OST0000-osc-ffff97d61279f000: Connection to lustre-OST0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2933.776533] LustreError: lustre-OST0000-osc-ffff97d61279f000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2933.793389] Lustre: lustre-OST0000-osc-ffff97d61279f000: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 2936.347875] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 12:27:10 (1772472430) [ 2936.677647] LustreError: 46970:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2939.704556] LustreError: 46970:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2946.536905] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 12:27:20 (1772472440) [ 2955.133372] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 12:27:28 (1772472448) [ 2963.502631] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2965.448747] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 12:27:39 (1772472459) [ 2967.242326] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2969.659535] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 12:27:43 (1772472463) [ 2974.702763] Lustre: lustre-MDT0000-mdc-ffff97d607985000: Connection to lustre-MDT0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2984.947593] LustreError: MGC192.168.202.116@tcp: Connection to MGS (at 192.168.202.116@tcp) was lost; in progress operations using this service will fail [ 2984.982782] Lustre: Evicted from MGS (at 192.168.202.116@tcp) after server handle changed from 0xf7238d1b78d88933 to 0xf7238d1b78e21226 [ 2984.993945] Lustre: MGC192.168.202.116@tcp: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 2989.503270] Lustre: lustre-MDT0000-mdc-ffff97d607985000: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 3034.347423] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 12:28:47 (1772472527) [ 3096.669491] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 12:29:50 (1772472590) [ 3118.622796] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 12:30:12 (1772472612) [ 3174.384661] Lustre: lustre-OST0000-osc-ffff97d607985000: Connection to lustre-OST0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3174.417277] Lustre: Skipped 1 previous similar message [ 3174.441256] LustreError: lustre-OST0000-osc-ffff97d607985000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3174.452692] LustreError: lustre-OST0000-osc-ffff97d61279f000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3174.458804] Lustre: lustre-OST0000-osc-ffff97d607985000: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 3174.511852] Lustre: Skipped 2 previous similar messages [ 3189.737103] Lustre: lustre-OST0001-osc-ffff97d607985000: Connection to lustre-OST0001 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3189.749776] Lustre: Skipped 1 previous similar message [ 3189.764230] LustreError: lustre-OST0001-osc-ffff97d607985000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 3189.795310] Lustre: lustre-OST0001-osc-ffff97d607985000: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 3194.849640] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 21s idle [ 3209.736354] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid 50 [ 3211.250957] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3215.246241] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid 50 [ 3216.742652] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3221.952928] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid 50 [ 3223.426841] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3227.486740] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid 50 [ 3229.255357] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3241.259331] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid 50 [ 3242.762968] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3247.322769] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid 50 [ 3249.407580] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff97d607985000.ost_server_uuid in IDLE state after 0 sec [ 3250.871072] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 12:32:25 (1772472745) [ 3254.026659] Lustre: DEBUG MARKER: Race attempt 0 [ 3257.813891] Lustre: DEBUG MARKER: Wait for 58335 58354 for 60 sec... [ 3328.251456] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 12:33:41 (1772472821) [ 3336.927767] Lustre: DEBUG MARKER: start test - cycle (0) [ 3368.956443] Lustre: DEBUG MARKER: start test - cycle (1) [ 3393.256233] Lustre: DEBUG MARKER: start test - cycle (2) [ 3417.585758] Lustre: DEBUG MARKER: start test - cycle (3) [ 3443.573487] Lustre: DEBUG MARKER: start test - cycle (4) [ 3470.029199] Lustre: DEBUG MARKER: start test - cycle (5) [ 3497.047188] Lustre: DEBUG MARKER: start test - cycle (6) [ 3502.049361] Lustre: lustre-OST0000-osc-ffff97d61279f000: disconnect after 21s idle [ 3502.064325] Lustre: Skipped 5 previous similar messages [ 3525.406243] Lustre: DEBUG MARKER: start test - cycle (7) [ 3551.251675] Lustre: DEBUG MARKER: start test - cycle (8) [ 3576.668389] Lustre: DEBUG MARKER: start test - cycle (9) [ 3603.583597] Lustre: DEBUG MARKER: start test - cycle (10) [ 3637.462246] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 12:38:51 (1772473131) [ 3731.014629] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 12:40:24 (1772473224) [ 3740.453935] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 12:40:34 (1772473234) [ 3750.452264] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 12:40:43 (1772473243) [ 3758.997956] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 12:40:53 (1772473253) [ 3759.418830] Lustre: *** cfs_fail_loc=411, val=0*** [ 3766.361609] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3768.249976] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 12:41:02 (1772473262) [ 3787.648763] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 12:41:21 (1772473281) [ 3808.314700] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 12:41:42 (1772473302) [ 3826.316916] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 12:42:00 (1772473320) [ 3849.533443] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 12:42:22 (1772473342) [ 3865.583862] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 12:42:39 (1772473359) [ 3881.756976] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 12:42:55 (1772473375) [ 3896.424995] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 12:43:09 (1772473389) [ 3912.716366] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 12:43:26 (1772473406) [ 3927.629236] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 12:43:41 (1772473421) [ 3944.852400] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 12:43:58 (1772473438) [ 3959.104460] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 12:44:13 (1772473453) [ 3973.235115] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 12:44:27 (1772473467) [ 4587.489871] Lustre: lustre-OST0001-osc-ffff97d61279f000: disconnect after 20s idle [ 4587.498730] Lustre: Skipped 19 previous similar messages [ 4924.150581] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 13:00:18 (1772474418) [ 4933.166838] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 13:00:27 (1772474427) [ 4942.658548] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 13:00:37 (1772474437) [ 4951.847113] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 13:00:46 (1772474446) [ 4962.124534] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 13:00:56 (1772474456) [ 4971.546613] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 13:01:05 (1772474465) [ 4980.457360] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 13:01:15 (1772474475) [ 4990.034102] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 13:01:24 (1772474484) [ 5000.169265] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 13:01:34 (1772474494) [ 5072.147538] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 13:02:46 (1772474566) [ 5081.410209] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 13:02:55 (1772474575) [ 5090.804498] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 13:03:05 (1772474585) [ 5101.190322] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 13:03:15 (1772474595) [ 5111.931378] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 13:03:26 (1772474606) [ 5121.511874] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 13:03:36 (1772474616) [ 5130.737883] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 13:03:45 (1772474625) [ 5140.463799] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 13:03:54 (1772474634) [ 5150.433711] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 13:04:05 (1772474645) [ 5231.880499] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 13:05:26 (1772474726) [ 5575.653623] Lustre: lustre-OST0001-osc-ffff97d607985000: disconnect after 21s idle [ 5575.661789] Lustre: Skipped 6 previous similar messages [ 5927.132895] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 13:17:02 (1772475422) [ 5933.115480] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 13:17:07 (1772475427) [ 5939.664739] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 13:17:14 (1772475434) [ 5946.170296] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 13:17:21 (1772475441) [ 5952.238038] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 13:17:27 (1772475447) [ 5958.264540] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 13:17:33 (1772475453) [ 5965.121510] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 13:17:39 (1772475459) [ 5971.624194] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 13:17:46 (1772475466) [ 5977.988583] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 13:17:52 (1772475472) [ 5984.524290] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 13:17:59 (1772475479) [ 6054.671752] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 13:19:09 (1772475549) [ 6061.222304] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 13:19:16 (1772475556) [ 6068.115717] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 13:19:22 (1772475562) [ 6074.760124] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 13:19:29 (1772475569) [ 6081.302735] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 13:19:36 (1772475576) [ 6088.058359] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 13:19:42 (1772475582) [ 6095.293211] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 13:19:50 (1772475590) [ 6102.229194] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 13:19:56 (1772475596) [ 6110.018182] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 13:20:04 (1772475604) [ 6241.249034] Lustre: lustre-OST0000-osc-ffff97d607985000: disconnect after 20s idle [ 6241.254633] Lustre: Skipped 4 previous similar messages [ 6633.297969] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 13:28:48 (1772476128) [ 6638.682070] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 13:28:53 (1772476133) [ 6643.991410] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 13:28:58 (1772476138) [ 6649.472524] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 13:29:04 (1772476144) [ 6654.851788] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 13:29:09 (1772476149) [ 6660.549468] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 13:29:15 (1772476155) [ 6666.349750] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 13:29:21 (1772476161) [ 6672.047892] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 13:29:26 (1772476166) [ 6677.917630] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 13:29:32 (1772476172) [ 6683.635778] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 13:29:38 (1772476178) [ 6689.099037] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 13:29:44 (1772476184) [ 6695.417257] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 13:29:50 (1772476190) [ 6700.602693] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 13:29:55 (1772476195) [ 6705.933281] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 13:30:00 (1772476200) [ 6711.331624] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 13:30:06 (1772476206) [ 6716.574303] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 13:30:11 (1772476211) [ 6723.276210] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 13:30:18 (1772476218) [ 6723.367969] LustreError: 22710:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6725.448093] LustreError: 22710:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6730.553747] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 13:30:25 (1772476225) [ 6734.867089] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 13:30:29 (1772476229) [ 6734.972406] LustreError: 239351:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6739.032117] LustreError: 239351:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6739.039287] LustreError: 239351:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6743.096119] LustreError: 239351:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6743.120084] LustreError: 239358:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6747.176114] LustreError: 239358:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6749.363668] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 13:30:44 (1772476244) [ 6756.081464] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 13:30:51 (1772476251) [ 6759.236117] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 13:30:54 (1772476254) [ 6763.350343] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 13:30:58 (1772476258) [ 6787.731513] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 13:31:22 (1772476282) [ 6795.538621] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 13:31:30 (1772476290) [ 6803.417963] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 13:31:38 (1772476298) [ 6816.620034] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 13:31:51 (1772476311) [ 6826.748148] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 13:32:01 (1772476321) [ 6840.033053] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 13:32:15 (1772476335) [ 6853.291796] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 13:32:28 (1772476348) [ 6867.439127] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 13:32:42 (1772476362) [ 6875.401942] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 13:32:50 (1772476370) [ 6907.443236] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 13:33:22 (1772476402) [ 6909.829157] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6912.026144] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 13:33:27 (1772476407) [ 6915.436045] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 13:33:30 (1772476410) [ 6917.854179] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 13:33:32 (1772476412) [ 6920.337205] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 13:33:35 (1772476415) [ 6932.670253] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 13:33:47 (1772476427) [ 6947.537188] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 13:34:02 (1772476442) [ 6949.790478] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 13:34:04 (1772476444) [ 6952.053804] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 13:34:07 (1772476447) [ 6955.157325] LustreError: lustre-MDT0000-mdc-ffff97d61279f000: operation ldlm_enqueue to node 192.168.202.116@tcp failed: rc = -35 [ 6955.162498] LustreError: Skipped 1 previous similar message [ 6958.505439] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 13:34:13 (1772476453) [ 6958.715762] LustreError: 2415:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6960.800110] LustreError: 2415:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6966.003298] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 13:34:20 (1772476460) [ 6983.648200] Lustre: lustre-OST0000-osc-ffff97d61279f000: disconnect after 25s idle [ 6983.651555] Lustre: Skipped 1 previous similar message [ 7025.463420] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 13:35:20 (1772476520) [ 7029.008986] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 13:35:23 (1772476523) [ 7033.520724] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 13:35:28 (1772476528) [ 7038.951905] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 13:35:33 (1772476533) [ 7044.622810] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 13:35:39 (1772476539) [ 7052.644812] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 13:35:47 (1772476547) [ 7060.868420] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 13:35:55 (1772476555) [ 7064.408309] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 13:35:59 (1772476559) [ 7068.375830] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 13:36:03 (1772476563) [ 7076.024640] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 13:36:10 (1772476570) [ 7117.825894] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 13:36:52 (1772476612) [ 7230.729751] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 13:38:45 (1772476725) [ 7344.012852] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 13:40:38 (1772476838) [ 7609.936899] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 13:45:04 (1772477104) [ 7631.595929] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 13:45:26 (1772477126) [ 7654.368126] Lustre: lustre-OST0001-osc-ffff97d607985000: disconnect after 22s idle [ 7654.371170] Lustre: Skipped 13 previous similar messages [ 7662.930621] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 13:45:57 (1772477157) [ 7682.237426] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 13:46:17 (1772477177) [ 7737.922636] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 13:47:12 (1772477232) [ 7789.562427] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 13:48:04 (1772477284) [ 7882.450702] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 13:49:37 (1772477377) [ 7883.459323] LustreError: 285978:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d607985000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7883.483088] Lustre: Unmounted lustre-client [ 7884.342587] LustreError: 285991:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d61279f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7884.346183] LustreError: 285991:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7884.364104] Lustre: Unmounted lustre-client [ 7941.524494] Lustre: Mounted lustre-client [ 7943.014307] Lustre: Mounted lustre-client [ 7943.973351] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8009.758899] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 13:51:44 (1772477504) [ 8017.201755] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 13:51:52 (1772477512) [ 8020.501815] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 13:51:55 (1772477515) [ 8070.869416] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 13:52:45 (1772477565) [ 8115.807092] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 13:53:30 (1772477610) [ 8119.690257] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 13:53:34 (1772477614) [ 8156.723989] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 13:54:11 (1772477651) [ 8168.189606] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 13:54:23 (1772477663) [ 8209.587249] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 13:55:04 (1772477704) [ 8212.468238] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 13:55:07 (1772477707) [ 8224.969968] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 13:55:20 (1772477720) [ 8228.430607] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 13:55:23 (1772477723) [ 8228.709630] LustreError: 307888:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x433:0x0]: rc = -5 [ 8228.712494] LustreError: 307888:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8229.287873] LustreError: 307940:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x446:0x0]: rc = -5 [ 8229.291791] LustreError: 307940:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [ 8229.293974] LustreError: 307940:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8229.296427] LustreError: 307940:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 9 previous similar messages [ 8230.289404] LustreError: 307726:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x461:0x0]: rc = -5 [ 8230.292570] LustreError: 307726:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 28 previous similar messages [ 8230.294906] LustreError: 307726:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8230.296893] LustreError: 307726:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 28 previous similar messages [ 8232.292967] LustreError: 308240:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x86:0x0]: rc = -5 [ 8232.295528] LustreError: 308240:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 73 previous similar messages [ 8232.297886] LustreError: 308240:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8232.300376] LustreError: 308240:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 73 previous similar messages [ 8236.303701] LustreError: 308607:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xe3:0x0]: rc = -5 [ 8236.307817] LustreError: 308607:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 143 previous similar messages [ 8236.310971] LustreError: 308607:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8236.314534] LustreError: 308607:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 143 previous similar messages [ 8244.315223] LustreError: 309325:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x62e:0x0]: rc = -5 [ 8244.319588] LustreError: 309325:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 278 previous similar messages [ 8244.322988] LustreError: 309325:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8244.325062] LustreError: 309325:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 278 previous similar messages [ 8275.936217] Lustre: lustre-OST0000-osc-ffff97d611d06800: disconnect after 22s idle [ 8275.938734] Lustre: Skipped 9 previous similar messages [ 8352.143369] LustreError: 307726:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x255:0x0]: rc = -5 [ 8352.146688] LustreError: 307726:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 161 previous similar messages [ 8352.148827] LustreError: 307726:0:(llite_lib.c:3742:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8352.150941] LustreError: 307726:0:(llite_lib.c:3742:ll_prep_inode()) Skipped 161 previous similar messages [ 8352.152921] LustreError: lustre-MDT0001-mdc-ffff97d602fe6800: operation mds_getattr_lock to node 192.168.202.116@tcp failed: rc = -107 [ 8352.155915] Lustre: lustre-MDT0001-mdc-ffff97d602fe6800: Connection to lustre-MDT0001 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8352.160383] LustreError: lustre-MDT0001-mdc-ffff97d602fe6800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 8352.164379] LustreError: 309736:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff97d602fe6800: inode [0x240000bd0:0x254:0x0] mdc close failed: rc = -108 [ 8352.172721] Lustre: lustre-MDT0001-mdc-ffff97d602fe6800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 8354.378393] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 13:57:29 (1772477849) [ 8356.480311] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 13:57:31 (1772477851) [ 8401.920479] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 13:58:16 (1772477896) [ 8402.420974] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 8402.956890] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 13:58:17 (1772477897) [ 8442.117404] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 13:58:57 (1772477937) [ 8444.344991] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 13:58:59 (1772477939) [ 8566.439862] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 14:01:01 (1772478061) [ 8573.909082] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 14:01:08 (1772478068) [ 8577.082272] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 14:01:12 (1772478072) [ 8759.267351] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 14:04:14 (1772478254) [ 8941.371991] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 14:07:16 (1772478436) [ 8943.295771] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 14:07:18 (1772478438) [ 8952.206690] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 14:07:27 (1772478447) [ 8952.269897] Lustre: DEBUG MARKER: write [ 8952.290618] LustreError: 288391:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8954.295891] Lustre: DEBUG MARKER: kill 371232 [ 8954.298456] LustreError: 371232:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8957.392109] LustreError: 288391:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8960.336091] LustreError: 371232:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8962.436508] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 14:07:37 (1772478457) [ 8964.586517] LustreError: 371845:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8974.680117] LustreError: 371845:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8976.939105] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 14:07:51 (1772478471) [ 8977.052435] LustreError: 372432:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8979.136076] LustreError: 372432:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [ 8985.117102] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 14:08:00 (1772478480) [ 8985.636820] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8986.278326] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 14:08:01 (1772478481) [ 8988.856986] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 14:08:03 (1772478483) [ 8991.159215] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 14:08:06 (1772478486) [ 8993.287925] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 14:08:08 (1772478488) [ 8995.256794] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 14:08:10 (1772478490) [ 8997.305290] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 14:08:12 (1772478492) [ 8999.255673] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 14:08:14 (1772478494) [ 9002.167254] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 14:08:17 (1772478497) [ 9004.708608] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 14:08:19 (1772478499) [ 9005.282133] Lustre: *** cfs_fail_loc=415, val=0*** [ 9011.817820] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 14:08:26 (1772478506) [ 9030.807876] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 14:08:45 (1772478525) [ 9030.897860] LustreError: 288391:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9030.900769] LustreError: 288391:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 9035.992104] LustreError: 287700:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 9035.994741] LustreError: 287700:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 9046.184102] LustreError: 288392:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 9046.186422] LustreError: 288392:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 9048.427164] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 14:09:03 (1772478543) [ 9050.771364] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 14:09:05 (1772478545) [ 9053.075196] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 14:09:08 (1772478548) [ 9055.263352] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 14:09:10 (1772478550) [ 9059.783899] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 14:09:14 (1772478554) [ 9068.240720] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 14:09:23 (1772478563) [ 9068.406817] LustreError: 383163:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 9068.411315] LustreError: 383163:0:(osc_request.c:2968:osc_build_rpc()) Skipped 6 previous similar messages [ 9072.472111] LustreError: 383163:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 9072.474256] LustreError: 383163:0:(osc_request.c:2968:osc_build_rpc()) Skipped 1 previous similar message [ 9074.689130] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 14:09:29 (1772478569) [ 9076.261097] LustreError: 383873:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d602fe6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9076.264579] LustreError: 383873:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9076.291757] Lustre: Unmounted lustre-client [ 9076.876139] LustreError: 383893:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d06800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9076.879557] LustreError: 383893:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9076.930858] Lustre: Unmounted lustre-client [ 9077.434208] Lustre: DEBUG MARKER: Iteration 1 [ 9077.522919] LustreError: 384056:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9077.529035] LustreError: 384057:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9077.532249] LustreError: 384056:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9077.583353] Lustre: Mounted lustre-client [ 9078.061667] LustreError: 384162:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6112ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9078.064854] LustreError: 384162:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 9078.128595] Lustre: Unmounted lustre-client [ 9079.065129] Key type lgssc unregistered [ 9079.202691] LNet: 384406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9079.205158] LNetError: 384406:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9079.215046] LNet: Removed LNI 192.168.202.16@tcp [ 9079.535143] Key type .llcrypt unregistered [ 9079.537043] Key type ._llcrypt unregistered [ 9079.956271] Key type ._llcrypt registered [ 9079.966630] Key type .llcrypt registered [ 9080.152259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9080.157778] alg: No test for adler32 (adler32-zlib) [ 9081.126263] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9081.403277] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9083.008156] Key type lgssc registered [ 9083.544227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9088.095020] Lustre: DEBUG MARKER: Iteration 2 [ 9088.205293] LustreError: 385238:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9088.205341] LustreError: 385239:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9088.212407] LustreError: 385238:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9089.265915] Lustre: Mounted lustre-client [ 9089.267529] Lustre: Skipped 1 previous similar message [ 9089.740700] LustreError: 385353:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399ad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9089.745316] LustreError: 385353:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9089.835833] Lustre: Unmounted lustre-client [ 9090.794073] Key type lgssc unregistered [ 9090.915556] LNet: 385596:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9090.918682] LNetError: 385596:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9090.925707] LNet: Removed LNI 192.168.202.16@tcp [ 9091.154197] Key type .llcrypt unregistered [ 9091.155884] Key type ._llcrypt unregistered [ 9091.423736] Key type ._llcrypt registered [ 9091.424886] Key type .llcrypt registered [ 9091.616976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9091.622784] alg: No test for adler32 (adler32-zlib) [ 9092.483502] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9092.573420] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9094.160160] Key type lgssc registered [ 9094.593230] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9097.984711] Lustre: DEBUG MARKER: Iteration 3 [ 9098.077239] LustreError: 386428:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9098.077539] LustreError: 386429:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9098.082144] LustreError: 386428:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9099.134794] Lustre: Mounted lustre-client [ 9099.136286] Lustre: Skipped 1 previous similar message [ 9099.549345] LustreError: 386542:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d639522000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9099.553143] LustreError: 386542:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9099.612193] Lustre: Unmounted lustre-client [ 9100.478830] Key type lgssc unregistered [ 9100.594694] LNet: 386785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9100.597568] LNetError: 386785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9100.607357] LNet: Removed LNI 192.168.202.16@tcp [ 9100.839129] Key type .llcrypt unregistered [ 9100.840564] Key type ._llcrypt unregistered [ 9101.067646] Key type ._llcrypt registered [ 9101.070527] Key type .llcrypt registered [ 9101.257361] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9101.264325] alg: No test for adler32 (adler32-zlib) [ 9102.110901] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9102.184530] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9103.760128] Key type lgssc registered [ 9104.129891] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9107.426491] Lustre: DEBUG MARKER: Iteration 4 [ 9107.515493] LustreError: 387616:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9107.515516] LustreError: 387617:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9107.518996] LustreError: 387616:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 9108.572225] Lustre: Mounted lustre-client [ 9108.573807] Lustre: Skipped 1 previous similar message [ 9108.989605] LustreError: 387731:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d639644800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9108.993283] LustreError: 387731:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9109.031285] Lustre: Unmounted lustre-client [ 9109.032693] Lustre: Skipped 1 previous similar message [ 9109.932796] Key type lgssc unregistered [ 9110.044438] LNet: 387975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9110.047342] LNetError: 387975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9110.056562] LNet: Removed LNI 192.168.202.16@tcp [ 9110.279081] Key type .llcrypt unregistered [ 9110.280318] Key type ._llcrypt unregistered [ 9110.477491] Key type ._llcrypt registered [ 9110.478480] Key type .llcrypt registered [ 9110.670530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9110.677464] alg: No test for adler32 (adler32-zlib) [ 9111.523882] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9111.598422] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9113.176103] Key type lgssc registered [ 9113.548443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9116.778578] Lustre: DEBUG MARKER: Iteration 5 [ 9116.875862] LustreError: 388807:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9116.876275] LustreError: 388808:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9116.881154] LustreError: 388807:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9117.944080] Lustre: Mounted lustre-client [ 9117.945541] Lustre: Skipped 1 previous similar message [ 9118.359679] LustreError: 388919:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d627e91000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9118.476188] Lustre: Unmounted lustre-client [ 9118.477734] Lustre: Skipped 1 previous similar message [ 9119.350446] Key type lgssc unregistered [ 9119.466471] LNet: 389163:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9119.468714] LNetError: 389163:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9119.478568] LNet: Removed LNI 192.168.202.16@tcp [ 9119.713140] Key type .llcrypt unregistered [ 9119.714214] Key type ._llcrypt unregistered [ 9119.973087] Key type ._llcrypt registered [ 9119.973956] Key type .llcrypt registered [ 9120.156879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9120.162848] alg: No test for adler32 (adler32-zlib) [ 9121.014814] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9121.095113] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9122.680091] Key type lgssc registered [ 9123.037260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9126.242223] Lustre: DEBUG MARKER: Iteration 6 [ 9126.328279] LustreError: 389994:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9126.328326] LustreError: 389995:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9126.333471] LustreError: 389994:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9127.389440] Lustre: Mounted lustre-client [ 9127.390992] Lustre: Skipped 1 previous similar message [ 9127.784374] LustreError: 390108:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d60881f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9127.787313] LustreError: 390108:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9127.837254] Lustre: Unmounted lustre-client [ 9127.838709] Lustre: Skipped 1 previous similar message [ 9128.721737] Key type lgssc unregistered [ 9128.833425] LNet: 390352:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9128.836089] LNetError: 390352:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9128.843650] LNet: Removed LNI 192.168.202.16@tcp [ 9129.066122] Key type .llcrypt unregistered [ 9129.067435] Key type ._llcrypt unregistered [ 9129.339536] Key type ._llcrypt registered [ 9129.341033] Key type .llcrypt registered [ 9129.516797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9129.523530] alg: No test for adler32 (adler32-zlib) [ 9130.365775] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9130.443344] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9132.032102] Key type lgssc registered [ 9132.398093] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9135.572581] Lustre: DEBUG MARKER: Iteration 7 [ 9135.662256] LustreError: 391183:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9135.663570] LustreError: 391184:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9135.667209] LustreError: 391183:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9136.715771] Lustre: Mounted lustre-client [ 9136.715771] Lustre: Mounted lustre-client [ 9137.127545] LustreError: 391297:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d603fd3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9137.130694] LustreError: 391297:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9137.182386] Lustre: Unmounted lustre-client [ 9137.985496] Key type lgssc unregistered [ 9138.097259] LNet: 391542:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9138.099049] LNetError: 391542:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9138.106521] LNet: Removed LNI 192.168.202.16@tcp [ 9138.302107] Key type .llcrypt unregistered [ 9138.303423] Key type ._llcrypt unregistered [ 9138.554045] Key type ._llcrypt registered [ 9138.555361] Key type .llcrypt registered [ 9138.725510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9138.729574] alg: No test for adler32 (adler32-zlib) [ 9139.587930] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9139.674904] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9141.256134] Key type lgssc registered [ 9141.591745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9144.863095] Lustre: DEBUG MARKER: Iteration 8 [ 9144.960905] LustreError: 392374:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9144.960947] LustreError: 392375:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9144.964499] LustreError: 392374:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9146.018581] Lustre: Mounted lustre-client [ 9146.019523] Lustre: Skipped 1 previous similar message [ 9146.536948] LustreError: 392489:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d627e92000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9146.539979] LustreError: 392489:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9146.613833] Lustre: Unmounted lustre-client [ 9146.614765] Lustre: Skipped 1 previous similar message [ 9147.528151] Key type lgssc unregistered [ 9147.641502] LNet: 392732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9147.644054] LNetError: 392732:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9147.650787] LNet: Removed LNI 192.168.202.16@tcp [ 9147.878153] Key type .llcrypt unregistered [ 9147.879755] Key type ._llcrypt unregistered [ 9148.111255] Key type ._llcrypt registered [ 9148.112127] Key type .llcrypt registered [ 9148.284859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9148.289536] alg: No test for adler32 (adler32-zlib) [ 9149.130196] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9149.207113] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9150.784137] Key type lgssc registered [ 9151.174678] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9155.016214] Lustre: DEBUG MARKER: Iteration 9 [ 9155.113651] LustreError: 393564:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9155.113724] LustreError: 393565:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9155.119089] LustreError: 393564:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9156.179997] Lustre: Mounted lustre-client [ 9156.181635] Lustre: Skipped 1 previous similar message [ 9156.734440] LustreError: 393678:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6092bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9156.738955] LustreError: 393678:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9156.825960] Lustre: Unmounted lustre-client [ 9157.827283] Key type lgssc unregistered [ 9157.940499] LNet: 393921:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9157.942812] LNetError: 393921:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9157.950822] LNet: Removed LNI 192.168.202.16@tcp [ 9158.210139] Key type .llcrypt unregistered [ 9158.211448] Key type ._llcrypt unregistered [ 9158.592213] Key type ._llcrypt registered [ 9158.598137] Key type .llcrypt registered [ 9158.744750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9158.751455] alg: No test for adler32 (adler32-zlib) [ 9159.621888] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9159.708215] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9161.296182] Key type lgssc registered [ 9161.644292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9165.212841] Lustre: DEBUG MARKER: Iteration 10 [ 9165.336817] LustreError: 394754:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9165.336880] LustreError: 394755:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9165.343631] LustreError: 394754:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9166.405067] Lustre: Mounted lustre-client [ 9166.911763] LustreError: 394867:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d77800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9166.916862] LustreError: 394867:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9167.074400] Lustre: Unmounted lustre-client [ 9167.076454] Lustre: Skipped 1 previous similar message [ 9168.054335] Key type lgssc unregistered [ 9168.163561] LNet: 395112:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9168.166444] LNetError: 395112:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9168.175676] LNet: Removed LNI 192.168.202.16@tcp [ 9168.432131] Key type .llcrypt unregistered [ 9168.433184] Key type ._llcrypt unregistered [ 9168.760104] Key type ._llcrypt registered [ 9168.760994] Key type .llcrypt registered [ 9168.954878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9168.960788] alg: No test for adler32 (adler32-zlib) [ 9169.827186] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9169.907884] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9171.496155] Key type lgssc registered [ 9171.871617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9175.779454] Lustre: DEBUG MARKER: Iteration 11 [ 9175.885597] LustreError: 395944:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9175.885622] LustreError: 395945:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9175.890347] LustreError: 395944:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9176.949567] Lustre: Mounted lustre-client [ 9176.950800] Lustre: Skipped 1 previous similar message [ 9177.478833] LustreError: 396056:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d63fb90800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9177.482309] LustreError: 396056:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9177.571131] Lustre: Unmounted lustre-client [ 9178.604209] Key type lgssc unregistered [ 9178.719611] LNet: 396300:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9178.721721] LNetError: 396300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9178.728284] LNet: Removed LNI 192.168.202.16@tcp [ 9178.961110] Key type .llcrypt unregistered [ 9178.962334] Key type ._llcrypt unregistered [ 9179.236836] Key type ._llcrypt registered [ 9179.237933] Key type .llcrypt registered [ 9179.413510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9179.419496] alg: No test for adler32 (adler32-zlib) [ 9180.259501] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9180.331156] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9181.912147] Key type lgssc registered [ 9182.258187] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9185.264539] Lustre: DEBUG MARKER: Iteration 12 [ 9185.358317] LustreError: 397131:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9185.358382] LustreError: 397132:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9185.362891] LustreError: 397131:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 9186.405589] Lustre: Mounted lustre-client [ 9186.799954] LustreError: 397245:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d70800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9186.804375] LustreError: 397245:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9186.859115] Lustre: Unmounted lustre-client [ 9187.677678] Key type lgssc unregistered [ 9187.779450] LNet: 397488:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9187.781297] LNetError: 397488:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9187.787730] LNet: Removed LNI 192.168.202.16@tcp [ 9187.995087] Key type .llcrypt unregistered [ 9187.996339] Key type ._llcrypt unregistered [ 9188.252092] Key type ._llcrypt registered [ 9188.252876] Key type .llcrypt registered [ 9188.403272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9188.407475] alg: No test for adler32 (adler32-zlib) [ 9189.258526] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9189.349753] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9190.928145] Key type lgssc registered [ 9191.314805] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9194.892449] Lustre: DEBUG MARKER: Iteration 13 [ 9194.994836] LustreError: 398320:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9194.995116] LustreError: 398321:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9195.000663] LustreError: 398320:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9196.055726] Lustre: Mounted lustre-client [ 9196.056832] Lustre: Skipped 1 previous similar message [ 9196.501404] LustreError: 398436:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d61f7d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9196.505205] LustreError: 398436:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9196.599095] Lustre: Unmounted lustre-client [ 9197.508860] Key type lgssc unregistered [ 9197.617481] LNet: 398679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9197.619910] LNetError: 398679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9197.626569] LNet: Removed LNI 192.168.202.16@tcp [ 9197.851101] Key type .llcrypt unregistered [ 9197.852038] Key type ._llcrypt unregistered [ 9198.207535] Key type ._llcrypt registered [ 9198.208563] Key type .llcrypt registered [ 9198.317332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9198.322327] alg: No test for adler32 (adler32-zlib) [ 9199.173516] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9199.258996] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9200.848129] Key type lgssc registered [ 9201.256595] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9204.734405] Lustre: DEBUG MARKER: Iteration 14 [ 9204.825123] LustreError: 399512:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9204.825161] LustreError: 399513:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9204.829241] LustreError: 399512:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9205.881076] Lustre: Mounted lustre-client [ 9205.882627] Lustre: Skipped 1 previous similar message [ 9206.357186] LustreError: 399625:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d612689000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9206.363609] LustreError: 399625:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9206.403136] Lustre: Unmounted lustre-client [ 9207.511385] Key type lgssc unregistered [ 9207.636341] LNet: 399868:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9207.638748] LNetError: 399868:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9207.646478] LNet: Removed LNI 192.168.202.16@tcp [ 9207.917109] Key type .llcrypt unregistered [ 9207.918223] Key type ._llcrypt unregistered [ 9208.241350] Key type ._llcrypt registered [ 9208.242508] Key type .llcrypt registered [ 9208.489879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9208.508870] alg: No test for adler32 (adler32-zlib) [ 9209.383080] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9209.472278] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9211.056119] Key type lgssc registered [ 9211.435979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9215.334353] Lustre: DEBUG MARKER: Iteration 15 [ 9215.441231] LustreError: 400699:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9215.441588] LustreError: 400700:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9215.445092] LustreError: 400699:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9216.502282] Lustre: Mounted lustre-client [ 9216.503983] Lustre: Skipped 1 previous similar message [ 9216.972416] LustreError: 400813:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d60d188800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9216.977147] LustreError: 400813:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9217.028754] Lustre: Unmounted lustre-client [ 9217.950067] Key type lgssc unregistered [ 9218.058515] LNet: 401058:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9218.060830] LNetError: 401058:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9218.068719] LNet: Removed LNI 192.168.202.16@tcp [ 9218.301119] Key type .llcrypt unregistered [ 9218.302153] Key type ._llcrypt unregistered [ 9218.611476] Key type ._llcrypt registered [ 9218.615558] Key type .llcrypt registered [ 9218.789888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9218.794752] alg: No test for adler32 (adler32-zlib) [ 9219.649843] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9219.745408] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9221.336232] Key type lgssc registered [ 9221.728224] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9225.386163] Lustre: DEBUG MARKER: Iteration 16 [ 9225.497272] LustreError: 401888:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9225.497314] LustreError: 401890:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9225.503160] LustreError: 401888:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9226.565391] Lustre: Mounted lustre-client [ 9226.567041] Lustre: Skipped 1 previous similar message [ 9227.008036] LustreError: 402004:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d61f7d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9227.012468] LustreError: 402004:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9227.092824] Lustre: Unmounted lustre-client [ 9228.071917] Key type lgssc unregistered [ 9228.187558] LNet: 402248:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9228.189695] LNetError: 402248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9228.199683] LNet: Removed LNI 192.168.202.16@tcp [ 9228.457120] Key type .llcrypt unregistered [ 9228.458771] Key type ._llcrypt unregistered [ 9228.750363] Key type ._llcrypt registered [ 9228.752244] Key type .llcrypt registered [ 9228.940441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9228.946826] alg: No test for adler32 (adler32-zlib) [ 9229.814136] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9229.896845] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9231.480133] Key type lgssc registered [ 9231.844238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9235.698687] Lustre: DEBUG MARKER: Iteration 17 [ 9235.796879] LustreError: 403080:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9235.796908] LustreError: 403081:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9235.803892] LustreError: 403080:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9236.874289] Lustre: Mounted lustre-client [ 9236.875473] Lustre: Skipped 1 previous similar message [ 9237.327861] LustreError: 403194:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9237.331216] LustreError: 403194:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9237.414144] Lustre: Unmounted lustre-client [ 9238.405436] Key type lgssc unregistered [ 9238.522519] LNet: 403437:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9238.524686] LNetError: 403437:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9238.531677] LNet: Removed LNI 192.168.202.16@tcp [ 9238.771130] Key type .llcrypt unregistered [ 9238.772916] Key type ._llcrypt unregistered [ 9239.194977] Key type ._llcrypt registered [ 9239.196069] Key type .llcrypt registered [ 9239.266934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9239.273235] alg: No test for adler32 (adler32-zlib) [ 9240.132087] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9240.217620] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9241.800137] Key type lgssc registered [ 9242.178510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9245.829086] Lustre: DEBUG MARKER: Iteration 18 [ 9245.934339] LustreError: 404270:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9245.935502] LustreError: 404269:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9245.938247] LustreError: 404270:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9246.994712] Lustre: Mounted lustre-client [ 9247.444144] LustreError: 404380:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399a9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9247.554549] Lustre: Unmounted lustre-client [ 9247.555771] Lustre: Skipped 1 previous similar message [ 9248.517427] Key type lgssc unregistered [ 9248.638544] LNet: 404624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9248.640678] LNetError: 404624:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9248.647666] LNet: Removed LNI 192.168.202.16@tcp [ 9248.883092] Key type .llcrypt unregistered [ 9248.884308] Key type ._llcrypt unregistered [ 9249.194180] Key type ._llcrypt registered [ 9249.195566] Key type .llcrypt registered [ 9249.437916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9249.444994] alg: No test for adler32 (adler32-zlib) [ 9250.312322] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9250.416358] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9252.008095] Key type lgssc registered [ 9252.372634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9255.991090] Lustre: DEBUG MARKER: Iteration 19 [ 9256.097287] LustreError: 405456:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9256.097365] LustreError: 405457:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9256.103102] LustreError: 405456:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9257.158775] Lustre: Mounted lustre-client [ 9257.611046] LustreError: 405572:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d603a73800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9257.614788] LustreError: 405572:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9257.680470] Lustre: Unmounted lustre-client [ 9257.681533] Lustre: Skipped 1 previous similar message [ 9258.682888] Key type lgssc unregistered [ 9258.798825] LNet: 405816:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9258.802628] LNetError: 405816:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9258.814969] LNet: Removed LNI 192.168.202.16@tcp [ 9259.068158] Key type .llcrypt unregistered [ 9259.069355] Key type ._llcrypt unregistered [ 9259.359050] Key type ._llcrypt registered [ 9259.361256] Key type .llcrypt registered [ 9259.554261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9259.569382] alg: No test for adler32 (adler32-zlib) [ 9260.424498] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9260.508198] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9262.088169] Key type lgssc registered [ 9262.492125] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9266.070887] Lustre: DEBUG MARKER: Iteration 20 [ 9266.181680] LustreError: 406648:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9266.181749] LustreError: 406649:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9266.185501] LustreError: 406648:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9267.236296] Lustre: Mounted lustre-client [ 9267.237350] Lustre: Skipped 1 previous similar message [ 9267.705540] LustreError: 406762:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6112e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9267.709571] LustreError: 406762:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9267.799080] Lustre: Unmounted lustre-client [ 9267.800657] Lustre: Skipped 1 previous similar message [ 9268.756970] Key type lgssc unregistered [ 9268.878581] LNet: 407007:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9268.882444] LNetError: 407007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9268.888754] LNet: Removed LNI 192.168.202.16@tcp [ 9269.124094] Key type .llcrypt unregistered [ 9269.125413] Key type ._llcrypt unregistered [ 9269.403341] Key type ._llcrypt registered [ 9269.404468] Key type .llcrypt registered [ 9269.587193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9269.592082] alg: No test for adler32 (adler32-zlib) [ 9270.463075] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9270.545609] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9272.136123] Key type lgssc registered [ 9272.532645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9276.352813] Lustre: DEBUG MARKER: Iteration 21 [ 9276.463585] LustreError: 407838:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9276.464818] LustreError: 407839:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9276.468886] LustreError: 407838:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9277.533736] Lustre: Mounted lustre-client [ 9277.990192] LustreError: 407952:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399d0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9277.995368] LustreError: 407952:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9278.062247] Lustre: Unmounted lustre-client [ 9278.993590] Key type lgssc unregistered [ 9279.115500] LNet: 408196:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9279.118773] LNetError: 408196:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9279.127888] LNet: Removed LNI 192.168.202.16@tcp [ 9279.368105] Key type .llcrypt unregistered [ 9279.369588] Key type ._llcrypt unregistered [ 9279.649441] Key type ._llcrypt registered [ 9279.650502] Key type .llcrypt registered [ 9279.855114] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9279.859939] alg: No test for adler32 (adler32-zlib) [ 9280.740437] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9280.828202] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9282.416154] Key type lgssc registered [ 9282.816801] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9286.587419] Lustre: DEBUG MARKER: Iteration 22 [ 9286.695473] LustreError: 409028:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9286.695542] LustreError: 409029:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9286.702021] LustreError: 409028:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9287.762476] Lustre: Mounted lustre-client [ 9288.247371] LustreError: 409135:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d635de8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9288.251072] LustreError: 409135:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9288.311187] Lustre: Unmounted lustre-client [ 9289.299204] Key type lgssc unregistered [ 9289.409479] LNet: 409377:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9289.411864] LNetError: 409377:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9289.422750] LNet: Removed LNI 192.168.202.16@tcp [ 9289.662089] Key type .llcrypt unregistered [ 9289.663213] Key type ._llcrypt unregistered [ 9289.944357] Key type ._llcrypt registered [ 9289.945531] Key type .llcrypt registered [ 9290.144563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9290.149967] alg: No test for adler32 (adler32-zlib) [ 9291.030177] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9291.117417] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9292.704170] Key type lgssc registered [ 9293.105398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9296.861973] Lustre: DEBUG MARKER: Iteration 23 [ 9296.968147] LustreError: 410209:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9296.969617] LustreError: 410210:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9296.973356] LustreError: 410209:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9298.027153] Lustre: Mounted lustre-client [ 9298.480315] LustreError: 410322:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d60298f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9298.485996] LustreError: 410322:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9298.582500] Lustre: Unmounted lustre-client [ 9299.590507] Key type lgssc unregistered [ 9299.703542] LNet: 410566:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9299.705700] LNetError: 410566:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9299.714655] LNet: Removed LNI 192.168.202.16@tcp [ 9299.955097] Key type .llcrypt unregistered [ 9299.956199] Key type ._llcrypt unregistered [ 9300.317972] Key type ._llcrypt registered [ 9300.319404] Key type .llcrypt registered [ 9300.429441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9300.434955] alg: No test for adler32 (adler32-zlib) [ 9301.295780] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9301.383590] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9302.968153] Key type lgssc registered [ 9303.338570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9306.959861] Lustre: DEBUG MARKER: Iteration 24 [ 9307.077089] LustreError: 411399:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9307.078177] LustreError: 411400:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9307.083556] LustreError: 411399:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9308.134163] Lustre: Mounted lustre-client [ 9308.608290] LustreError: 411511:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d603a70000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9308.614505] LustreError: 411511:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9308.702281] Lustre: Unmounted lustre-client [ 9309.789869] Key type lgssc unregistered [ 9309.914817] LNet: 411754:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9309.917649] LNetError: 411754:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9309.928520] LNet: Removed LNI 192.168.202.16@tcp [ 9310.214175] Key type .llcrypt unregistered [ 9310.215890] Key type ._llcrypt unregistered [ 9310.672289] Key type ._llcrypt registered [ 9310.673364] Key type .llcrypt registered [ 9310.854633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9310.862023] alg: No test for adler32 (adler32-zlib) [ 9311.731674] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9311.826525] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9313.416152] Key type lgssc registered [ 9313.875437] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9317.704880] Lustre: DEBUG MARKER: Iteration 25 [ 9317.828402] LustreError: 412586:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9317.828427] LustreError: 412587:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9317.836687] LustreError: 412586:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9318.889963] Lustre: Mounted lustre-client [ 9319.350696] LustreError: 412693:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d62ca92000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9319.356075] LustreError: 412693:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9319.422557] Lustre: Unmounted lustre-client [ 9320.455156] Key type lgssc unregistered [ 9320.582445] LNet: 412936:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9320.585432] LNetError: 412936:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9320.593733] LNet: Removed LNI 192.168.202.16@tcp [ 9320.820104] Key type .llcrypt unregistered [ 9320.821228] Key type ._llcrypt unregistered [ 9321.102222] Key type ._llcrypt registered [ 9321.103895] Key type .llcrypt registered [ 9321.313055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9321.318499] alg: No test for adler32 (adler32-zlib) [ 9322.187106] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9322.275742] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9323.856213] Key type lgssc registered [ 9324.231184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9327.865481] Lustre: DEBUG MARKER: Iteration 26 [ 9327.968339] LustreError: 413768:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9327.968688] LustreError: 413769:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9327.972635] LustreError: 413768:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9329.028336] Lustre: Mounted lustre-client [ 9329.492420] LustreError: 413882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d609b09800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9329.496755] LustreError: 413882:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9329.557156] Lustre: Unmounted lustre-client [ 9330.602689] Key type lgssc unregistered [ 9330.719475] LNet: 414125:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9330.722730] LNetError: 414125:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9330.732181] LNet: Removed LNI 192.168.202.16@tcp [ 9330.976097] Key type .llcrypt unregistered [ 9330.977607] Key type ._llcrypt unregistered [ 9331.242728] Key type ._llcrypt registered [ 9331.243876] Key type .llcrypt registered [ 9331.417743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9331.426685] alg: No test for adler32 (adler32-zlib) [ 9332.290399] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9332.379892] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9333.960189] Key type lgssc registered [ 9334.361185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9338.007752] Lustre: DEBUG MARKER: Iteration 27 [ 9338.112262] LustreError: 414957:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9338.112292] LustreError: 414956:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9338.116101] LustreError: 414957:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9339.178964] Lustre: Mounted lustre-client [ 9339.180144] Lustre: Skipped 1 previous similar message [ 9339.647430] LustreError: 415068:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9339.654208] LustreError: 415068:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9339.696921] Lustre: Unmounted lustre-client [ 9340.679533] Key type lgssc unregistered [ 9340.803869] LNet: 415311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9340.807303] LNetError: 415311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9340.815910] LNet: Removed LNI 192.168.202.16@tcp [ 9341.077128] Key type .llcrypt unregistered [ 9341.078890] Key type ._llcrypt unregistered [ 9341.392825] Key type ._llcrypt registered [ 9341.394563] Key type .llcrypt registered [ 9341.549214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9341.554545] alg: No test for adler32 (adler32-zlib) [ 9342.427930] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9342.512046] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9344.096142] Key type lgssc registered [ 9344.500766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9348.270769] Lustre: DEBUG MARKER: Iteration 28 [ 9348.392752] LustreError: 416143:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9348.392850] LustreError: 416144:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9348.399447] LustreError: 416143:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9349.468849] Lustre: Mounted lustre-client [ 9349.470783] Lustre: Skipped 1 previous similar message [ 9349.943667] LustreError: 416258:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091bb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9349.946832] LustreError: 416258:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9350.047116] Lustre: Unmounted lustre-client [ 9350.048318] Lustre: Skipped 1 previous similar message [ 9351.065109] Key type lgssc unregistered [ 9351.185534] LNet: 416501:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9351.187755] LNetError: 416501:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9351.195738] LNet: Removed LNI 192.168.202.16@tcp [ 9351.422098] Key type .llcrypt unregistered [ 9351.423177] Key type ._llcrypt unregistered [ 9351.676507] Key type ._llcrypt registered [ 9351.677569] Key type .llcrypt registered [ 9351.892163] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9351.897536] alg: No test for adler32 (adler32-zlib) [ 9352.755286] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9352.845693] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9354.432166] Key type lgssc registered [ 9354.887970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9358.812733] Lustre: DEBUG MARKER: Iteration 29 [ 9358.954741] LustreError: 417334:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9358.954817] LustreError: 417335:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9358.959216] LustreError: 417334:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9360.023089] Lustre: Mounted lustre-client [ 9360.612711] LustreError: 417446:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d612680000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9360.615889] LustreError: 417446:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9360.722238] Lustre: Unmounted lustre-client [ 9361.738729] Key type lgssc unregistered [ 9361.860499] LNet: 417690:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9361.863314] LNetError: 417690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9361.871774] LNet: Removed LNI 192.168.202.16@tcp [ 9362.084116] Key type .llcrypt unregistered [ 9362.085134] Key type ._llcrypt unregistered [ 9362.367864] Key type ._llcrypt registered [ 9362.368883] Key type .llcrypt registered [ 9362.571160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9362.577714] alg: No test for adler32 (adler32-zlib) [ 9363.436994] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9363.527748] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9365.112143] Key type lgssc registered [ 9365.492481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9368.980275] Lustre: DEBUG MARKER: Iteration 30 [ 9369.080561] LustreError: 418522:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9369.080622] LustreError: 418523:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9369.086259] LustreError: 418522:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9370.138882] Lustre: Mounted lustre-client [ 9370.668933] LustreError: 418635:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d62ea5a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9370.674699] LustreError: 418635:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9370.739331] Lustre: Unmounted lustre-client [ 9371.812306] Key type lgssc unregistered [ 9371.937471] LNet: 418879:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9371.939772] LNetError: 418879:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9371.949753] LNet: Removed LNI 192.168.202.16@tcp [ 9372.193116] Key type .llcrypt unregistered [ 9372.194242] Key type ._llcrypt unregistered [ 9372.484919] Key type ._llcrypt registered [ 9372.486164] Key type .llcrypt registered [ 9372.690126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9372.695172] alg: No test for adler32 (adler32-zlib) [ 9373.557676] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9373.658934] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9375.248181] Key type lgssc registered [ 9375.687346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9379.200273] Lustre: DEBUG MARKER: Iteration 31 [ 9379.310386] LustreError: 419710:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9379.310437] LustreError: 419711:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9379.314781] LustreError: 419710:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9380.367636] Lustre: Mounted lustre-client [ 9380.809178] LustreError: 419822:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d76800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9380.812715] LustreError: 419822:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9380.864148] Lustre: Unmounted lustre-client [ 9381.778401] Key type lgssc unregistered [ 9381.891551] LNet: 420066:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9381.893836] LNetError: 420066:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9381.905733] LNet: Removed LNI 192.168.202.16@tcp [ 9382.134087] Key type .llcrypt unregistered [ 9382.135475] Key type ._llcrypt unregistered [ 9382.409467] Key type ._llcrypt registered [ 9382.410512] Key type .llcrypt registered [ 9382.602056] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9382.608137] alg: No test for adler32 (adler32-zlib) [ 9383.469545] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9383.556284] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9385.136158] Key type lgssc registered [ 9385.519328] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9389.047427] Lustre: DEBUG MARKER: Iteration 32 [ 9389.163338] LustreError: 420899:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9389.163366] LustreError: 420900:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9389.169592] LustreError: 420899:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9390.237102] Lustre: Mounted lustre-client [ 9390.238140] Lustre: Skipped 1 previous similar message [ 9390.687991] LustreError: 421012:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6112ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9390.692231] LustreError: 421012:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9390.777105] Lustre: Unmounted lustre-client [ 9391.760756] Key type lgssc unregistered [ 9391.890359] LNet: 421256:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9391.894132] LNetError: 421256:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9391.902548] LNet: Removed LNI 192.168.202.16@tcp [ 9392.140128] Key type .llcrypt unregistered [ 9392.141379] Key type ._llcrypt unregistered [ 9392.501225] Key type ._llcrypt registered [ 9392.502419] Key type .llcrypt registered [ 9392.625718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9392.630502] alg: No test for adler32 (adler32-zlib) [ 9393.486199] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9393.567195] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9395.152141] Key type lgssc registered [ 9395.575963] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9399.309411] Lustre: DEBUG MARKER: Iteration 33 [ 9399.421429] LustreError: 422088:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9399.422121] LustreError: 422089:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9399.426406] LustreError: 422088:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9400.484089] Lustre: Mounted lustre-client [ 9400.485141] Lustre: Skipped 1 previous similar message [ 9400.922520] LustreError: 422203:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9400.925449] LustreError: 422203:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9400.989531] Lustre: Unmounted lustre-client [ 9401.883594] Key type lgssc unregistered [ 9401.996492] LNet: 422446:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9401.998941] LNetError: 422446:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9402.005698] LNet: Removed LNI 192.168.202.16@tcp [ 9402.231091] Key type .llcrypt unregistered [ 9402.232072] Key type ._llcrypt unregistered [ 9402.472882] Key type ._llcrypt registered [ 9402.474025] Key type .llcrypt registered [ 9402.680450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9402.684952] alg: No test for adler32 (adler32-zlib) [ 9403.546549] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9403.628817] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9405.208152] Key type lgssc registered [ 9405.581602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9408.977459] Lustre: DEBUG MARKER: Iteration 34 [ 9409.096711] LustreError: 423278:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9409.096749] LustreError: 423279:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9409.101491] LustreError: 423278:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9410.163294] Lustre: Mounted lustre-client [ 9410.666981] LustreError: 423389:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d60b597800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9410.670286] LustreError: 423389:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9410.735061] Lustre: Unmounted lustre-client [ 9411.620396] Key type lgssc unregistered [ 9411.735475] LNet: 423634:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9411.737601] LNetError: 423634:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9411.747707] LNet: Removed LNI 192.168.202.16@tcp [ 9411.958092] Key type .llcrypt unregistered [ 9411.959178] Key type ._llcrypt unregistered [ 9412.220891] Key type ._llcrypt registered [ 9412.222874] Key type .llcrypt registered [ 9412.415067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9412.421987] alg: No test for adler32 (adler32-zlib) [ 9413.280965] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9413.363444] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9414.952185] Key type lgssc registered [ 9415.341732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9418.916230] Lustre: DEBUG MARKER: Iteration 35 [ 9419.029458] LustreError: 424467:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9419.029482] LustreError: 424468:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9419.034113] LustreError: 424467:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9420.095371] Lustre: Mounted lustre-client [ 9420.097265] Lustre: Skipped 1 previous similar message [ 9420.686902] LustreError: 424580:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d612a84000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9420.690446] LustreError: 424580:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9420.773099] Lustre: Unmounted lustre-client [ 9421.863367] Key type lgssc unregistered [ 9421.988834] LNet: 424824:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9421.992198] LNetError: 424824:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9422.000505] LNet: Removed LNI 192.168.202.16@tcp [ 9422.282111] Key type .llcrypt unregistered [ 9422.283270] Key type ._llcrypt unregistered [ 9422.583664] Key type ._llcrypt registered [ 9422.585022] Key type .llcrypt registered [ 9422.807642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9422.813142] alg: No test for adler32 (adler32-zlib) [ 9423.693445] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9423.790553] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9425.376158] Key type lgssc registered [ 9425.785901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9429.413664] Lustre: DEBUG MARKER: Iteration 36 [ 9429.539455] LustreError: 425655:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9429.539501] LustreError: 425656:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9429.545070] LustreError: 425655:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9430.618946] Lustre: Mounted lustre-client [ 9431.179576] LustreError: 425766:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d612d48800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9431.182782] LustreError: 425766:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9431.249350] Lustre: Unmounted lustre-client [ 9431.250955] Lustre: Skipped 1 previous similar message [ 9432.238935] Key type lgssc unregistered [ 9432.360715] LNet: 426011:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9432.363537] LNetError: 426011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9432.376759] LNet: Removed LNI 192.168.202.16@tcp [ 9432.666128] Key type .llcrypt unregistered [ 9432.667247] Key type ._llcrypt unregistered [ 9433.025420] Key type ._llcrypt registered [ 9433.026417] Key type .llcrypt registered [ 9433.240741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9433.245627] alg: No test for adler32 (adler32-zlib) [ 9434.118663] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9434.213044] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9435.808163] Key type lgssc registered [ 9436.215339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9439.799561] Lustre: DEBUG MARKER: Iteration 37 [ 9439.908713] LustreError: 426844:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9439.909170] LustreError: 426845:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9439.915045] LustreError: 426844:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9440.976844] Lustre: Mounted lustre-client [ 9440.978594] Lustre: Skipped 1 previous similar message [ 9441.414033] LustreError: 426959:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399a9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9441.529652] Lustre: Unmounted lustre-client [ 9441.530670] Lustre: Skipped 1 previous similar message [ 9442.425529] Key type lgssc unregistered [ 9442.530481] LNet: 427202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9442.533446] LNetError: 427202:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9442.543767] LNet: Removed LNI 192.168.202.16@tcp [ 9442.792106] Key type .llcrypt unregistered [ 9442.793176] Key type ._llcrypt unregistered [ 9443.122970] Key type ._llcrypt registered [ 9443.124026] Key type .llcrypt registered [ 9443.340258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9443.350493] alg: No test for adler32 (adler32-zlib) [ 9444.204446] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9444.281164] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9445.864107] Key type lgssc registered [ 9446.219930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9449.512541] Lustre: DEBUG MARKER: Iteration 38 [ 9449.624392] LustreError: 428036:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9449.625159] LustreError: 428035:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9449.629180] LustreError: 428036:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9450.685800] Lustre: Mounted lustre-client [ 9450.687437] Lustre: Skipped 1 previous similar message [ 9451.142115] LustreError: 428151:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091bb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9451.146404] LustreError: 428151:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9451.230650] Lustre: Unmounted lustre-client [ 9451.232530] Lustre: Skipped 1 previous similar message [ 9452.186186] Key type lgssc unregistered [ 9452.301525] LNet: 428395:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9452.304322] LNetError: 428395:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9452.312708] LNet: Removed LNI 192.168.202.16@tcp [ 9452.537133] Key type .llcrypt unregistered [ 9452.539062] Key type ._llcrypt unregistered [ 9452.796548] Key type ._llcrypt registered [ 9452.797694] Key type .llcrypt registered [ 9452.991192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9452.995945] alg: No test for adler32 (adler32-zlib) [ 9453.860475] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9453.942356] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9455.520076] Key type lgssc registered [ 9455.882299] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9459.360258] Lustre: DEBUG MARKER: Iteration 39 [ 9459.472890] LustreError: 429226:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9459.472941] LustreError: 429227:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9459.477324] LustreError: 429226:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9460.530026] Lustre: Mounted lustre-client [ 9460.531889] Lustre: Skipped 1 previous similar message [ 9461.000952] LustreError: 429342:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9461.125145] Lustre: Unmounted lustre-client [ 9461.127101] Lustre: Skipped 1 previous similar message [ 9462.112095] Key type lgssc unregistered [ 9462.229493] LNet: 429585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9462.231650] LNetError: 429585:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9462.239632] LNet: Removed LNI 192.168.202.16@tcp [ 9462.466109] Key type .llcrypt unregistered [ 9462.467197] Key type ._llcrypt unregistered [ 9462.750215] Key type ._llcrypt registered [ 9462.752295] Key type .llcrypt registered [ 9462.949147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9462.954643] alg: No test for adler32 (adler32-zlib) [ 9463.815962] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9463.898884] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9465.480111] Key type lgssc registered [ 9465.863631] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9469.459497] Lustre: DEBUG MARKER: Iteration 40 [ 9469.571303] LustreError: 430418:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9469.572388] LustreError: 430417:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9469.576062] LustreError: 430418:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9470.628948] Lustre: Mounted lustre-client [ 9471.087129] LustreError: 430529:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d75000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9471.092063] LustreError: 430529:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9471.167028] Lustre: Unmounted lustre-client [ 9472.105062] Key type lgssc unregistered [ 9472.224552] LNet: 430772:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9472.226643] LNetError: 430772:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9472.236679] LNet: Removed LNI 192.168.202.16@tcp [ 9472.448175] Key type .llcrypt unregistered [ 9472.449243] Key type ._llcrypt unregistered [ 9472.735848] Key type ._llcrypt registered [ 9472.737896] Key type .llcrypt registered [ 9472.920456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9472.925957] alg: No test for adler32 (adler32-zlib) [ 9473.793836] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9473.879935] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9475.456204] Key type lgssc registered [ 9475.830089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9479.261450] Lustre: DEBUG MARKER: Iteration 41 [ 9479.363972] LustreError: 431604:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9479.364040] LustreError: 431603:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9479.369374] LustreError: 431604:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9480.418262] Lustre: Mounted lustre-client [ 9480.420188] Lustre: Skipped 1 previous similar message [ 9480.917718] LustreError: 431717:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d63b8e6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9480.921477] LustreError: 431717:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9480.993112] Lustre: Unmounted lustre-client [ 9481.966086] Key type lgssc unregistered [ 9482.088543] LNet: 431960:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9482.090736] LNetError: 431960:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9482.097477] LNet: Removed LNI 192.168.202.16@tcp [ 9482.323111] Key type .llcrypt unregistered [ 9482.324480] Key type ._llcrypt unregistered [ 9482.731302] Key type ._llcrypt registered [ 9482.732841] Key type .llcrypt registered [ 9482.811209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9482.817695] alg: No test for adler32 (adler32-zlib) [ 9483.688242] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9483.782671] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9485.368159] Key type lgssc registered [ 9485.761338] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9489.378146] Lustre: DEBUG MARKER: Iteration 42 [ 9489.506239] LustreError: 432790:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9489.506946] LustreError: 432793:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9489.512033] LustreError: 432790:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9490.575061] Lustre: Mounted lustre-client [ 9490.576148] Lustre: Skipped 1 previous similar message [ 9491.146713] LustreError: 432903:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d602bae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9491.150745] LustreError: 432903:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9491.243142] Lustre: Unmounted lustre-client [ 9492.319135] Key type lgssc unregistered [ 9492.430421] LNet: 433147:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9492.432840] LNetError: 433147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9492.440602] LNet: Removed LNI 192.168.202.16@tcp [ 9492.690119] Key type .llcrypt unregistered [ 9492.691403] Key type ._llcrypt unregistered [ 9492.992266] Key type ._llcrypt registered [ 9492.993682] Key type .llcrypt registered [ 9493.159465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9493.165159] alg: No test for adler32 (adler32-zlib) [ 9494.030818] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9494.116958] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9495.696183] Key type lgssc registered [ 9496.091538] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9499.696491] Lustre: DEBUG MARKER: Iteration 43 [ 9499.840646] LustreError: 433979:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9499.842893] LustreError: 433980:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9499.844852] LustreError: 433979:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9500.918530] Lustre: Mounted lustre-client [ 9501.482716] LustreError: 434093:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9501.487437] LustreError: 434093:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9501.567227] Lustre: Unmounted lustre-client [ 9502.567590] Key type lgssc unregistered [ 9502.689508] LNet: 434339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9502.692127] LNetError: 434339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9502.699764] LNet: Removed LNI 192.168.202.16@tcp [ 9502.959124] Key type .llcrypt unregistered [ 9502.960048] Key type ._llcrypt unregistered [ 9503.330154] Key type ._llcrypt registered [ 9503.334833] Key type .llcrypt registered [ 9503.489935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9503.496809] alg: No test for adler32 (adler32-zlib) [ 9504.370036] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9504.463849] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9506.056143] Key type lgssc registered [ 9506.446050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9510.223902] Lustre: DEBUG MARKER: Iteration 44 [ 9510.328214] LustreError: 435170:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9510.329985] LustreError: 435171:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9510.336134] LustreError: 435170:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9511.399129] Lustre: Mounted lustre-client [ 9511.400961] Lustre: Skipped 1 previous similar message [ 9511.978787] LustreError: 435284:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d611d77000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9511.983561] LustreError: 435284:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9512.050112] Lustre: Unmounted lustre-client [ 9512.051834] Lustre: Skipped 1 previous similar message [ 9513.113857] Key type lgssc unregistered [ 9513.241840] LNet: 435527:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9513.245255] LNetError: 435527:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9513.253460] LNet: Removed LNI 192.168.202.16@tcp [ 9513.530122] Key type .llcrypt unregistered [ 9513.531270] Key type ._llcrypt unregistered [ 9513.816771] Key type ._llcrypt registered [ 9513.818403] Key type .llcrypt registered [ 9514.083637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9514.102128] alg: No test for adler32 (adler32-zlib) [ 9514.980729] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9515.097605] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9516.696154] Key type lgssc registered [ 9517.117912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9520.975331] Lustre: DEBUG MARKER: Iteration 45 [ 9521.094434] LustreError: 436358:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9521.094753] LustreError: 436359:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9521.099451] LustreError: 436358:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9522.155292] Lustre: Mounted lustre-client [ 9522.614266] LustreError: 436470:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d63ba08000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9522.618584] LustreError: 436470:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9522.701302] Lustre: Unmounted lustre-client [ 9523.683049] Key type lgssc unregistered [ 9523.796772] LNet: 436715:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9523.799257] LNetError: 436715:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9523.812139] LNet: Removed LNI 192.168.202.16@tcp [ 9524.056146] Key type .llcrypt unregistered [ 9524.057396] Key type ._llcrypt unregistered [ 9524.335576] Key type ._llcrypt registered [ 9524.336745] Key type .llcrypt registered [ 9524.593649] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9524.599252] alg: No test for adler32 (adler32-zlib) [ 9525.477502] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9525.571776] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9527.168156] Key type lgssc registered [ 9527.616681] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9531.217029] Lustre: DEBUG MARKER: Iteration 46 [ 9531.321994] LustreError: 437547:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9531.322068] LustreError: 437546:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9531.327148] LustreError: 437547:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9532.385201] Lustre: Mounted lustre-client [ 9532.829177] LustreError: 437657:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d639646000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9532.832303] LustreError: 437657:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9532.937993] Lustre: Unmounted lustre-client [ 9533.848811] Key type lgssc unregistered [ 9533.968463] LNet: 437901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9533.972350] LNetError: 437901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9533.982600] LNet: Removed LNI 192.168.202.16@tcp [ 9534.225110] Key type .llcrypt unregistered [ 9534.226059] Key type ._llcrypt unregistered [ 9534.485933] Key type ._llcrypt registered [ 9534.488138] Key type .llcrypt registered [ 9534.696212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9534.703054] alg: No test for adler32 (adler32-zlib) [ 9535.579403] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9535.675097] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9537.264239] Key type lgssc registered [ 9537.714765] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9541.660785] Lustre: DEBUG MARKER: Iteration 47 [ 9541.771936] LustreError: 438733:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9541.772280] LustreError: 438734:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9541.776198] LustreError: 438733:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9542.834565] Lustre: Mounted lustre-client [ 9543.389724] LustreError: 438846:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d602a01800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9543.453959] Lustre: Unmounted lustre-client [ 9543.455086] Lustre: Skipped 1 previous similar message [ 9544.419160] Key type lgssc unregistered [ 9544.529572] LNet: 439089:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9544.531794] LNetError: 439089:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9544.541686] LNet: Removed LNI 192.168.202.16@tcp [ 9544.788088] Key type .llcrypt unregistered [ 9544.789103] Key type ._llcrypt unregistered [ 9545.098455] Key type ._llcrypt registered [ 9545.099646] Key type .llcrypt registered [ 9545.297808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9545.302947] alg: No test for adler32 (adler32-zlib) [ 9546.180352] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9546.273120] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9547.864269] Key type lgssc registered [ 9548.317464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9552.064852] Lustre: DEBUG MARKER: Iteration 48 [ 9552.172116] LustreError: 439919:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9552.172138] LustreError: 439920:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9552.177486] LustreError: 439919:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9553.232897] Lustre: Mounted lustre-client [ 9553.233993] Lustre: Skipped 1 previous similar message [ 9553.788331] LustreError: 440031:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d603415800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9553.791898] LustreError: 440031:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9553.851969] Lustre: Unmounted lustre-client [ 9554.929312] Key type lgssc unregistered [ 9555.045420] LNet: 440275:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9555.049533] LNetError: 440275:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9555.061350] LNet: Removed LNI 192.168.202.16@tcp [ 9555.320104] Key type .llcrypt unregistered [ 9555.321339] Key type ._llcrypt unregistered [ 9555.694159] Key type ._llcrypt registered [ 9555.704434] Key type .llcrypt registered [ 9555.947938] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9555.953056] alg: No test for adler32 (adler32-zlib) [ 9556.833779] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9556.928884] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9558.520146] Key type lgssc registered [ 9558.948887] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9562.548904] Lustre: DEBUG MARKER: Iteration 49 [ 9562.648616] LustreError: 441109:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9562.648646] LustreError: 441108:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9562.653522] LustreError: 441109:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9563.718847] Lustre: Mounted lustre-client [ 9564.175814] LustreError: 441216:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d60b6cc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9564.244484] Lustre: Unmounted lustre-client [ 9564.246101] Lustre: Skipped 1 previous similar message [ 9565.114681] Key type lgssc unregistered [ 9565.225437] LNet: 441461:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9565.228298] LNetError: 441461:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9565.234803] LNet: Removed LNI 192.168.202.16@tcp [ 9565.463117] Key type .llcrypt unregistered [ 9565.464261] Key type ._llcrypt unregistered [ 9565.812072] Key type ._llcrypt registered [ 9565.815175] Key type .llcrypt registered [ 9566.096260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9566.106546] alg: No test for adler32 (adler32-zlib) [ 9566.978399] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9567.068996] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9568.656121] Key type lgssc registered [ 9569.020130] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9572.918662] Lustre: DEBUG MARKER: Iteration 50 [ 9573.021799] LustreError: 442294:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 9573.021844] LustreError: 442295:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 9573.026330] LustreError: 442294:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9574.080253] Lustre: Mounted lustre-client [ 9574.082523] Lustre: Skipped 1 previous similar message [ 9574.536903] LustreError: 442404:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6399ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9574.540899] LustreError: 442404:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9574.610084] Lustre: Unmounted lustre-client [ 9575.557807] Key type lgssc unregistered [ 9575.682411] LNet: 442648:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9575.684557] LNetError: 442648:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9575.693870] LNet: Removed LNI 192.168.202.16@tcp [ 9575.957121] Key type .llcrypt unregistered [ 9575.958908] Key type ._llcrypt unregistered [ 9576.276464] Key type ._llcrypt registered [ 9576.278320] Key type .llcrypt registered [ 9576.526919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9576.534362] alg: No test for adler32 (adler32-zlib) [ 9577.392498] Lustre: Lustre: Build Version: 2.17.50_194_gae5720b [ 9577.471617] LNet: Added LNI 192.168.202.16@tcp [8/256/0/180] [ 9579.048144] Key type lgssc registered [ 9579.442924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9583.426241] Lustre: Mounted lustre-client [ 9585.736492] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 14:18:00 (1772479080) [ 9602.528084] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479081/real 1772479081] req@ffff97d6112f8a80 x1858579015411584/t0(0) o36->lustre-MDT0000-mdc-ffff97d641c09800@192.168.202.116@tcp:12/10 lens 496/440 e 0 to 1 dl 1772479097 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9602.534854] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection to lustre-MDT0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9602.542964] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9618.912161] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479098/real 1772479098] req@ffff97d6112f8a80 x1858579015411584/t0(0) o36->lustre-MDT0000-mdc-ffff97d641c09800@192.168.202.116@tcp:12/10 lens 496/440 e 0 to 1 dl 1772479114 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9618.919085] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection to lustre-MDT0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9618.928098] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9635.296115] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479114/real 1772479114] req@ffff97d6112f8a80 x1858579015411584/t0(0) o36->lustre-MDT0000-mdc-ffff97d641c09800@192.168.202.116@tcp:12/10 lens 496/440 e 0 to 1 dl 1772479130 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9635.303055] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection to lustre-MDT0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9635.311478] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9650.656169] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479130/real 1772479130] req@ffff97d6112f8a80 x1858579015411584/t0(0) o36->lustre-MDT0000-mdc-ffff97d641c09800@192.168.202.116@tcp:12/10 lens 496/440 e 0 to 1 dl 1772479146 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9650.665937] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection to lustre-MDT0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9650.674542] Lustre: lustre-MDT0000-mdc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9651.163104] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 14:19:06 (1772479146) [ 9656.883526] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 14:19:11 (1772479151) [ 9660.152729] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 14:19:15 (1772479155) [ 9662.347525] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 14:19:17 (1772479157) [ 9669.494156] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 14:19:24 (1772479164) [ 9681.643230] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 14:19:36 (1772479176) [ 9684.055111] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 14:19:39 (1772479179) [ 9684.131317] LustreError: 448777:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 9686.216153] LustreError: 448777:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 9688.297170] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 14:19:43 (1772479183) [ 9705.952198] Lustre: 442840:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479185/real 1772479185] req@ffff97d636c3b800 x1858579016447104/t0(0) o4->lustre-OST0000-osc-ffff97d641c09800@192.168.202.116@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772479201 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9705.952249] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection to lustre-OST0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9705.959919] Lustre: 442840:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 9722.336272] Lustre: 442842:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479201/real 1772479201] req@ffff97d62d8be300 x1858579016445952/t0(0) o4->lustre-OST0000-osc-ffff97d641c09800@192.168.202.116@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772479217 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9722.336348] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection to lustre-OST0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9722.351407] Lustre: 442842:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 7 previous similar messages [ 9722.369078] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9737.698164] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9754.080091] Lustre: 442840:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772479233/real 1772479233] req@ffff97d62e599500 x1858579016446720/t0(0) o4->lustre-OST0000-osc-ffff97d641c09800@192.168.202.116@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772479249 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9754.091451] Lustre: 442840:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 9754.094898] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection to lustre-OST0000 (at 192.168.202.116@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9754.100831] Lustre: Skipped 1 previous similar message [ 9754.110911] Lustre: lustre-OST0000-osc-ffff97d641c09800: Connection restored to 192.168.202.116@tcp (at 192.168.202.116@tcp) [ 9777.437445] Lustre: DEBUG MARKER: oleg216-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff97d6091bf800.ost_server_uuid 50 [ 9777.927101] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff97d6091bf800.ost_server_uuid in IDLE state after 0 sec [ 9778.546381] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9779.185284] Lustre: DEBUG MARKER: == sanityn test complete, duration 9414 sec ============== 14:21:14 (1772479274) [ 9779.788590] Lustre: DEBUG MARKER: === sanityn: start cleanup 14:21:14 (1772479274) === [ 9844.009612] LustreError: 450829:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d6091bf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9844.032087] Lustre: Unmounted lustre-client [ 9845.572758] Lustre: DEBUG MARKER: === sanityn: finish cleanup 14:22:20 (1772479340) === [ 9845.935373] LustreError: 451133:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff97d641c09800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9845.938272] LustreError: 451133:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9845.971282] Lustre: Unmounted lustre-client [ 9882.942554] Key type lgssc unregistered [ 9883.046519] LNet: 451817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9883.048664] LNetError: 451817:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9883.057949] LNet: Removed LNI 192.168.202.16@tcp [ 9883.333103] Key type .llcrypt unregistered [ 9883.334430] Key type ._llcrypt unregistered