[ 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 463758189 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.996 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 2448MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.003248] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008851] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 0.009032] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399996) [ 0.010019] pid_max: default: 32768 minimum: 301 [ 0.011186] LSM: Security Framework initializing [ 0.012072] Yama: becoming mindful. [ 0.013058] SELinux: Initializing. [ 0.014080] *** VALIDATE selinux *** [ 0.019168] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024488] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025191] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027125] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028164] *** VALIDATE tmpfs *** [ 0.030227] *** VALIDATE proc *** [ 0.031286] *** VALIDATE cgroup *** [ 0.032013] *** VALIDATE cgroup2 *** [ 0.033289] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034174] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036035] Spectre V2 : User space: Vulnerable [ 0.037009] Speculative Store Bypass: Vulnerable [ 0.040417] debug: unmapping init [mem 0xffffffffb5659000-0xffffffffb5660fff] [ 0.042286] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043715] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044029] ... version: 2 [ 0.045013] ... bit width: 48 [ 0.046014] ... generic registers: 4 [ 0.047014] ... value mask: 0000ffffffffffff [ 0.048014] ... max period: 00007fffffffffff [ 0.049020] ... fixed-purpose events: 3 [ 0.050013] ... event mask: 000000070000000f [ 0.051351] rcu: Hierarchical SRCU implementation. [ 0.053876] smp: Bringing up secondary CPUs ... [ 0.054702] x86: Booting SMP configuration: [ 0.055026] .... node #0, CPUs: #1 #2 #3 [ 0.065028] smp: Brought up 1 node, 4 CPUs [ 0.067016] smpboot: Max logical packages: 1 [ 0.068020] smpboot: Total of 4 processors activated (19199.96 BogoMIPS) [ 0.174173] node 0 deferred pages initialised in 101ms [ 0.177296] devtmpfs: initialized [ 0.179337] x86/mm: Memory block size: 128MB [ 0.183203] gcov: version magic: 0x41383552 [ 0.185362] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.189239] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.192722] pinctrl core: initialized pinctrl subsystem [ 0.195448] [ 0.196007] ************************************************************* [ 0.200021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.203011] ** ** [ 0.206057] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.209015] ** ** [ 0.211014] ** This means that this kernel is built to expose internal ** [ 0.214039] ** IOMMU data structures, which may compromise security on ** [ 0.218044] ** your system. ** [ 0.220012] ** ** [ 0.223039] ** If you see this message and you are not debugging the ** [ 0.224012] ** kernel, report this immediately to your vendor! ** [ 0.227015] ** ** [ 0.229012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.231018] ************************************************************* [ 0.235968] NET: Registered protocol family 16 [ 0.239985] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.243075] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.246088] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.252157] cpuidle: using governor menu [ 0.255857] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.263083] PCI: Using configuration type 1 for base access [ 0.267428] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.287091] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.289275] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.296186] cryptd: max_cpu_qlen set to 1000 [ 0.300541] ACPI: Added _OSI(Module Device) [ 0.303140] ACPI: Added _OSI(Processor Device) [ 0.305032] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.307022] ACPI: Added _OSI(Processor Aggregator Device) [ 0.314111] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.322494] ACPI: Interpreter enabled [ 0.324057] ACPI: PM: (supports S0 S3 S4 S5) [ 0.326014] ACPI: Using IOAPIC for interrupt routing [ 0.327142] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.330461] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.345041] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.349059] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.352030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.356253] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.361462] acpiphp: Slot [2] registered [ 0.363091] acpiphp: Slot [5] registered [ 0.364188] acpiphp: Slot [6] registered [ 0.365081] acpiphp: Slot [3] registered [ 0.366079] acpiphp: Slot [4] registered [ 0.368177] acpiphp: Slot [7] registered [ 0.369106] acpiphp: Slot [8] registered [ 0.371086] acpiphp: Slot [9] registered [ 0.372153] acpiphp: Slot [10] registered [ 0.374175] acpiphp: Slot [11] registered [ 0.376292] acpiphp: Slot [12] registered [ 0.379177] acpiphp: Slot [13] registered [ 0.381292] acpiphp: Slot [14] registered [ 0.385554] acpiphp: Slot [15] registered [ 0.387155] acpiphp: Slot [16] registered [ 0.389200] acpiphp: Slot [17] registered [ 0.391159] acpiphp: Slot [18] registered [ 0.393907] acpiphp: Slot [19] registered [ 0.397229] acpiphp: Slot [20] registered [ 0.400422] acpiphp: Slot [21] registered [ 0.402142] acpiphp: Slot [22] registered [ 0.404157] acpiphp: Slot [23] registered [ 0.406149] acpiphp: Slot [24] registered [ 0.407130] acpiphp: Slot [25] registered [ 0.409153] acpiphp: Slot [26] registered [ 0.411123] acpiphp: Slot [27] registered [ 0.413388] acpiphp: Slot [28] registered [ 0.415132] acpiphp: Slot [29] registered [ 0.416156] acpiphp: Slot [30] registered [ 0.418129] acpiphp: Slot [31] registered [ 0.419067] PCI host bridge to bus 0000:00 [ 0.421020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.424084] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.427021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.430026] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.432030] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.435165] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.437191] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.442000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.445835] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.454016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.458047] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.460016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.463025] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.466027] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.468621] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.473041] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.475184] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.481000] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.486021] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.497015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.501926] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.511724] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.518028] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.530026] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.565025] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.576140] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.584019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.590018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.606021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.620410] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.623519] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.626742] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.628502] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.632677] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.640046] iommu: Default domain type: Passthrough [ 0.642989] SCSI subsystem initialized [ 0.645557] ACPI: bus type USB registered [ 0.649198] usbcore: registered new interface driver usbfs [ 0.652478] usbcore: registered new interface driver hub [ 0.656091] usbcore: registered new device driver usb [ 0.658349] pps_core: LinuxPPS API ver. 1 registered [ 0.662012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.667074] PTP clock support registered [ 0.670077] EDAC MC: Ver: 3.0.0 [ 0.672025] PCI: Using ACPI for IRQ routing [ 0.673000] NetLabel: Initializing [ 0.674131] NetLabel: domain hash size = 128 [ 0.677012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.679293] NetLabel: unlabeled traffic allowed by default [ 0.682616] vgaarb: loaded [ 0.685008] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.687022] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.695861] clocksource: Switched to clocksource kvm-clock [ 0.871955] VFS: Disk quotas dquot_6.6.0 [ 0.873792] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.877133] *** VALIDATE ramfs *** [ 0.878827] *** VALIDATE hugetlbfs *** [ 0.880723] pnp: PnP ACPI init [ 0.883645] pnp: PnP ACPI: found 6 devices [ 0.906089] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.911621] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.914317] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.917022] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.920095] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.923067] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.926917] NET: Registered protocol family 2 [ 0.929966] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.936642] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.941861] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.949251] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.953310] TCP: Hash tables configured (established 65536 bind 65536) [ 0.957717] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.961965] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.965740] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.969725] NET: Registered protocol family 1 [ 0.974851] RPC: Registered named UNIX socket transport module. [ 0.979919] RPC: Registered udp transport module. [ 0.983137] RPC: Registered tcp transport module. [ 0.986222] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.990292] NET: Registered protocol family 44 [ 0.994117] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.998621] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.003615] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.007174] PCI: CLS 0 bytes, default 64 [ 1.009218] Unpacking initramfs... [ 2.830982] debug: unmapping init [mem 0xffff8ff33cc64000-0xffff8ff33ffcffff] [ 2.838130] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.840636] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 2.859193] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 3.827697] Initialise system trusted keyrings [ 3.843056] Key type blacklist registered [ 3.846074] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.859781] zbud: loaded [ 3.863285] *** VALIDATE nfs *** [ 3.864573] *** VALIDATE nfs4 *** [ 3.866343] pstore: using deflate compression [ 3.870612] Platform Keyring initialized [ 3.986734] NET: Registered protocol family 38 [ 3.988310] Key type asymmetric registered [ 3.989817] Asymmetric key parser 'x509' registered [ 3.992654] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.996325] io scheduler mq-deadline registered [ 3.998514] io scheduler kyber registered [ 4.000339] io scheduler bfq registered [ 4.003527] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.007229] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.010518] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.014193] ACPI: Power Button [PWRF] [ 4.020529] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.034271] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.047769] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.079903] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.110541] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.115552] Non-volatile memory driver v1.3 [ 4.117321] Linux agpgart interface v0.103 [ 4.158788] virtio_blk virtio1: [vda] 135128 512-byte logical blocks (69.2 MB/66.0 MiB) [ 4.162208] vda: detected capacity change from 0 to 69185536 [ 4.218231] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.221928] vdb: detected capacity change from 0 to 1073741824 [ 4.232711] libphy: Fixed MDIO Bus: probed [ 4.246872] usbcore: registered new interface driver usbserial_generic [ 4.248676] usbserial: USB Serial support registered for generic [ 4.250928] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.255566] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.257273] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.259651] mousedev: PS/2 mouse device common for all mice [ 4.264660] rtc_cmos 00:05: RTC can wake from S4 [ 4.267782] rtc_cmos 00:05: registered as rtc0 [ 4.270286] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.274738] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.276959] intel_pstate: CPU model not supported [ 4.279249] hid: raw HID events driver (C) Jiri Kosina [ 4.288060] usbcore: registered new interface driver usbhid [ 4.290237] usbhid: USB HID core driver [ 4.294723] drop_monitor: Initializing network drop monitor service [ 4.296092] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.299653] Initializing XFRM netlink socket [ 4.304814] NET: Registered protocol family 10 [ 4.308643] Segment Routing with IPv6 [ 4.309899] NET: Registered protocol family 17 [ 4.311312] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.315173] mpls_gso: MPLS GSO support [ 4.324182] RAS: Correctable Errors collector initialized. [ 4.326214] AVX version of gcm_enc/dec engaged. [ 4.327922] AES CTR mode by8 optimization enabled [ 4.413807] sched_clock: Marking stable (4413658942, 0)->(5482789721, -1069130779) [ 4.419877] registered taskstats version 1 [ 4.422362] Loading compiled-in X.509 certificates [ 4.423887] zswap: loaded using pool lzo/zbud [ 4.449714] Key type big_key registered [ 4.464454] Key type encrypted registered [ 4.466139] ima: No TPM chip found, activating TPM-bypass! [ 4.468519] ima: Allocated hash algorithm: sha1 [ 4.470322] ima: No architecture policies found [ 4.472363] evm: Initialising EVM extended attributes: [ 4.474466] evm: security.selinux [ 4.475832] evm: security.ima [ 4.477126] evm: security.capability [ 4.478686] evm: HMAC attrs: 0x1 [ 4.481279] rtc_cmos 00:05: setting system clock to 2026-03-10 00:17:17 UTC (1773101837) [ 4.488739] debug: unmapping init [mem 0xffffffffb6603000-0xffffffffb67fffff] [ 4.492351] debug: unmapping init [mem 0xffffffffb5382000-0xffffffffb5658fff] [ 4.501140] Write protecting the kernel read-only data: 28672k [ 4.503711] debug: unmapping init [mem 0xffffffffb3a03000-0xffffffffb3bfffff] [ 4.506510] debug: unmapping init [mem 0xffffffffb4314000-0xffffffffb43fffff] [ 4.533216] 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.539318] systemd[1]: Detected virtualization kvm. [ 4.541261] systemd[1]: Detected architecture x86-64. [ 4.543641] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.584803] systemd[1]: No hostname configured. [ 4.586646] systemd[1]: Set hostname to . [ 4.588941] random: systemd: uninitialized urandom read (16 bytes read) [ 4.590905] systemd[1]: Initializing machine ID from random generator. [ 4.714836] random: ln: uninitialized urandom read (6 bytes read) [ 4.873345] random: systemd: uninitialized urandom read (16 bytes read) [ 4.876504] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.882348] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.887904] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.888613] device-mapper: uevent: version 1.0.3 [ 5.891610] 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... [ OK ] Started udev Coldplug all Devices. Mounting Kernel Configuration File System... Starting dracut initqueue hook... [ OK ] Mounted Kernel Configuration File System. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ 7.462520] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 7.537588] random: fast init done [ 8.080769] scsi host0: ata_piix [ 8.264921] scsi host1: ata_piix [ 8.287839] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.297595] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.999943] random: crng init done [ 14.001318] random: 7 urandom warning(s) missed due to ratelimiting [ 14.793281] dracut-initqueue[570]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 16.269797] 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 Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 18.718578] printk: systemd: 26 output lines suppressed due to ratelimiting [ 19.143320] SELinux: Disabled at runtime. [ 19.244454] 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) [ 19.255865] systemd[1]: Detected virtualization kvm. [ 19.258624] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 20.085890] systemd[1]: initrd-switch-root.service: Succeeded. [ 20.091978] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 20.109745] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 20.115901] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 20.136835] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 20.173964] systemd[1]: Starting Journal Service... Starting Journal Service... [ 20.187186] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Huge Pages File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd Root File System. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe[ 20.605027] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS . [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 21.892481] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 22.458028] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 22.518871] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 23.701297] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 23.788552] EDAC sbridge: Ver: 1.1.2 [ 25.992625] Key type dns_resolver registered [ 26.341620] NFS: Registering the id_resolver key type [ 26.343726] Key type id_resolver registered [ 26.345496] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg214-client login: [ 37.757169] hrtimer: interrupt took 8852306 ns [ 64.111596] libcfs: loading out-of-tree module taints kernel. [ 64.234571] Key type ._llcrypt registered [ 64.245054] Key type .llcrypt registered [ 64.532576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 64.539962] alg: No test for adler32 (adler32-zlib) [ 65.637553] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 65.994382] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 67.623150] Key type lgssc registered [ 68.319669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 144.475268] Lustre: Mounted lustre-client [ 147.247673] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 161.516248] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing check_logdir /tmp/testlogs/ [ 163.782641] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing yml_node [ 166.385768] Lustre: DEBUG MARKER: Client: 2.17.50.236 [ 167.982733] Lustre: DEBUG MARKER: MDS: 2.17.50.236 [ 169.513113] Lustre: DEBUG MARKER: OSS: 2.17.50.236 [ 169.952231] Lustre: lustre-OST0000-osc-ffff8ff38655e800: disconnect after 23s idle [ 170.450491] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Mar 9 20:20:02 EDT 2026 [ 179.570332] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 180.392168] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 181.108731] Lustre: DEBUG MARKER: === sanityn: start setup 20:20:13 (1773102013) === [ 181.447438] Lustre: Mounted lustre-client [ 183.167172] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing check_config_client /mnt/lustre [ 193.222521] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 200.547882] Lustre: DEBUG MARKER: === sanityn: finish setup 20:20:32 (1773102032) === [ 201.710381] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 20:20:33 (1773102033) [ 202.208931] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 21s idle [ 202.218938] Lustre: Skipped 1 previous similar message [ 206.242459] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 20:20:38 (1773102038) [ 209.949476] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 20:20:42 (1773102042) [ 213.509334] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 20:20:45 (1773102045) [ 216.702549] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 20:20:48 (1773102048) [ 219.898724] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 20:20:52 (1773102052) [ 223.472806] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 20:20:55 (1773102055) [ 227.363164] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 20:20:59 (1773102059) [ 231.412881] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 20:21:03 (1773102063) [ 235.477303] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 20:21:07 (1773102067) [ 239.529552] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 20:21:11 (1773102071) [ 244.315900] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 20:21:16 (1773102076) [ 247.860916] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 20:21:20 (1773102080) [ 251.428198] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 20:21:23 (1773102083) [ 255.049426] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 20:21:27 (1773102087) [ 258.354564] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 20:21:30 (1773102090) [ 261.788725] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 20:21:34 (1773102094) [ 265.230240] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 20:21:37 (1773102097) [ 268.868626] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 20:21:41 (1773102101) [ 272.223573] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 20:21:44 (1773102104) [ 275.975771] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 20:21:48 (1773102108) [ 276.303063] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 418.209255] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 20:24:10 (1773102250) [ 423.351294] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 20:24:15 (1773102255) [ 427.675975] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 20:24:19 (1773102259) [ 431.500868] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 20:24:23 (1773102263) [ 436.274860] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 20:24:28 (1773102268) [ 441.231664] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 20:24:33 (1773102273) [ 442.475832] Lustre: DEBUG MARKER: chmod [ 446.624577] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 20:24:38 (1773102278) [ 956.018725] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 20:33:08 (1773102788) [ 1041.888478] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 22s idle [ 1041.892323] Lustre: Skipped 1 previous similar message [ 1064.640474] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 20:34:56 (1773102896) [ 1103.004601] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:35:35 (1773102935) [ 1166.289461] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:36:38 (1773102998) [ 1183.349237] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:36:55 (1773103015) [ 1186.578861] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:36:58 (1773103018) [ 1187.024170] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.062865] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.093286] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.131320] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.167598] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.197166] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.221934] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.250115] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.277444] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.315595] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.349807] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.388488] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.418896] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.454881] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.489974] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.530166] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.564826] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.612446] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.669133] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.705786] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.747438] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.778658] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.820928] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.861800] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.891922] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.919905] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.944381] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1187.978422] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.005577] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.033249] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.059804] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.092648] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.133473] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.170157] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.209970] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.260463] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.296743] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.339346] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.375150] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.423351] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.469261] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.523413] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.566686] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.608422] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.653313] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.704831] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.757629] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.792669] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.841361] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.899037] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.946001] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1188.990846] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.020900] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.058825] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.093699] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.145638] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.195001] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.244983] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.310175] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.373251] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.419882] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.461856] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.495340] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.532719] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.565607] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.601182] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.643597] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.696414] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.741668] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.776487] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.807809] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.846626] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.880977] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.914400] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.942539] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1189.973558] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.002111] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.032953] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.074090] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.103547] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.133501] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.163435] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.200391] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.223571] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.251777] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.294030] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.322799] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.347368] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.378085] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.404685] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.431402] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.459950] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.490618] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.521084] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.564570] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.610616] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.644661] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.681334] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.724453] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.761735] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.800907] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.833812] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.860114] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.904983] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1190.959604] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.009671] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.049599] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.091473] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.126541] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.168406] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.196319] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.235445] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.272842] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.299658] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.331847] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.359885] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.393946] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.424667] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.466837] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.508210] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.559038] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.594687] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.621538] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.653426] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.688512] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.729678] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.766624] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.792622] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.824673] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.854665] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.884214] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.916747] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.953858] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1191.989468] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.051566] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.078328] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.107510] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.144964] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.179283] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.211393] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.247881] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.284145] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.310167] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.335065] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.368025] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.401582] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.449242] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.476550] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.507524] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.535459] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.577136] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.605522] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.645291] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.687177] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.715406] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.751812] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.807737] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.846826] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.885825] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.934661] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1192.965322] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.000533] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.035487] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.076342] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.119483] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.153354] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.190955] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.230663] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.280257] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.335924] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.377117] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.410443] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.462539] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.496645] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.534255] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.565300] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.604444] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.650196] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.677805] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.722801] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.768594] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.815367] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.863428] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.904723] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.947252] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1193.982086] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.006481] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.040483] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.066721] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.105489] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.141921] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.174795] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.205296] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.246417] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.273381] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.323218] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.371354] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.402330] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.442347] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.484327] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.514595] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.545527] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.578539] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.610174] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.641643] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.674452] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.704694] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.751534] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.785876] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.818416] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.850184] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.881207] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.910497] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.934528] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1194.975877] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.002973] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.031482] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.062845] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.093667] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.124636] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.160482] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.214950] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.242639] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.289707] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.315833] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.359997] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.391068] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.435297] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.469725] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.499807] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.552411] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.597344] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.639871] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.684281] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.720242] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.761565] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.787842] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.820138] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.850955] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.886164] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.928520] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1195.969675] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.012539] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.042218] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.089151] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.136870] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.180895] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.226509] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.260672] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.294259] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.317291] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.345948] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.378499] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.409706] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.442538] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.479410] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.530213] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.605570] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.674169] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.726293] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.785502] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.833948] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.871104] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.907091] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.946283] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1196.990657] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.025722] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.062759] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.096220] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.133755] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.168535] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.218306] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.263507] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.310848] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.350772] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.383804] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.424401] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.459826] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.503571] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.544977] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.597816] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.641510] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.687050] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.737500] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.783232] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.823303] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.867683] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.911628] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.947650] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1197.991765] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.032501] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.064791] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.093516] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.132700] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.169535] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.212171] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.243676] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.276993] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.306144] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.347383] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.385496] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.418137] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.465739] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.513573] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.554687] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.589986] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.623943] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.660125] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.696814] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.730900] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.763530] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.801500] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.836526] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.871508] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.911678] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1198.962291] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.020142] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.049824] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.086581] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.119409] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.150225] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.186144] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.241090] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.285776] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.334623] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.369927] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.424962] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.453054] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.484101] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.513953] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.546367] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.575442] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.609721] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.650372] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.681708] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.721390] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.764505] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.805043] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.838486] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.872787] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.903653] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.934397] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1199.982460] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.023482] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.054958] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.096422] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.135422] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.183416] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.225583] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.259906] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.304696] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.340270] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.368311] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.415464] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.446354] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.479677] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.505285] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.541284] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.579731] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.620795] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.658464] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.712630] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.750300] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.779192] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.811777] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.848104] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.886483] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.923507] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1200.966272] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.010915] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.043950] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.069245] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.097061] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.127823] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.169354] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.218355] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.255192] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.292472] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.325596] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.357644] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.393631] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.428466] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.456512] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.485375] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.523732] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.563150] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.602151] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.634223] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.672063] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.705600] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.733531] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.767233] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.809930] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.838277] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.873644] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.901713] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.936523] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.967634] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1201.998717] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.028569] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.052935] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.085362] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.118067] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.143594] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.178786] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.217083] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.252708] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.284291] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.321441] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.354172] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.390573] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.430244] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.468765] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.507627] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.546362] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.596537] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.636452] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.677489] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.718626] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.752715] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.791351] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.818664] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.856315] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.893876] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.930845] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1202.972717] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.003165] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.047371] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.094151] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.146122] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.186105] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.231667] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.276032] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.324781] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.360427] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.403425] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.441595] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.479918] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.515783] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.562766] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.603763] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.650571] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.687580] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.736782] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.775347] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.822495] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.848462] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.879773] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.911811] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.946806] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1203.985922] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.028878] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.066843] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.095726] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.126711] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.161519] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.202863] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.234125] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.281707] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.320488] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.356262] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.387840] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.418994] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.451734] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.490956] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.532486] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.567783] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.603168] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.629672] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.658558] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.693062] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.719673] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.744034] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.782203] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.817398] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.844608] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.878406] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.907131] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.940281] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1204.976412] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.002609] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.033766] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.057343] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.082081] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.109788] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.135907] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.180980] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.216781] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.250937] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.279958] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.305064] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.330339] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.366773] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.400712] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.431923] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.460763] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.495703] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.543051] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.576482] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.613693] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.649274] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.679194] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.706739] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.727627] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 22s idle [ 1205.733747] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.778394] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.814943] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.847292] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.881291] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.916312] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.956433] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1205.989481] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.021608] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.058689] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.092452] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.123519] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.160295] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.194607] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.231356] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.263244] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.295070] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.325496] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.357389] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.391159] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.435514] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.484747] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.515625] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.548579] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.579219] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.609632] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1206.638191] rw_seq_cst_vs_d (32353): drop_caches: 3 [ 1210.076917] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:37:22 (1773103042) [ 1210.244384] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.375653] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.406628] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.492381] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.604726] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.641676] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.679481] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.745246] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.762376] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.798257] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.822722] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.858819] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.908329] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.931801] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.972313] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1210.990306] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.028225] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.053216] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.069965] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.100710] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.126857] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.320256] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.346973] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.436287] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.475553] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.495480] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.515924] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.539934] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.559787] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.588405] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.623723] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.645659] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.670347] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.695129] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.882444] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.913815] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1211.981030] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.069536] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.088467] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.153954] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.178903] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.209573] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.289514] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.315341] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.408885] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.569433] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.598713] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.617996] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.672799] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.724608] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.742456] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.812845] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.953644] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1212.989519] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.137462] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.168654] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.186383] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.338359] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.363851] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.394867] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.416354] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.440427] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.461248] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1213.478979] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.012384] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.030808] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.094494] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.113381] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.195338] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.221247] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.298392] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.322098] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.384415] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.403486] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.434426] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.454480] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.480518] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.518938] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.588485] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.677150] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.810544] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1214.979155] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.008803] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.073074] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.104182] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.149903] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.230992] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.254205] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.329520] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.452335] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.479438] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.532754] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.631928] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.771860] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.811443] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.859177] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.890465] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.942276] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1215.992136] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.059631] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.080288] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.134974] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.171634] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.280701] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.299083] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.319240] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.486420] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.503891] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.520873] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.539044] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.555581] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.716525] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.736343] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.762236] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.798388] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.956225] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.977161] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1216.999068] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.020424] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.303693] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.328151] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.366791] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.401064] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.444410] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.491133] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.548704] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.582675] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.611544] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.629990] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.651829] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.676225] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.696247] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.810947] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1217.993780] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.143516] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.237715] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.374293] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.672582] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.726771] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.749888] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1218.813623] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.023473] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.048099] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.213385] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.248800] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.380340] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.415573] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.464081] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.493281] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.515677] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.541543] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.571467] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.665378] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.697843] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.718864] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.745624] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.786564] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.826904] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.847519] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.866614] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.890336] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.921335] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.947571] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1219.975530] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.139636] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.165793] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.221177] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.239214] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.257017] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.284313] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.469724] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.496831] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.540164] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.621361] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.643470] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.806534] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.824954] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.869576] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.889134] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.913814] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.975510] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1220.999358] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.021504] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.121153] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.194482] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.424497] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.456075] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.557193] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.576887] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.608764] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.728716] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.804915] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.830085] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.879983] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.923055] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.940625] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1221.976364] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.063184] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.086919] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.350892] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.494163] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.560698] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.646594] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.947497] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1222.965780] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.368888] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.395280] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.419097] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.440251] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.751687] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.768101] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.812470] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.903995] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.925887] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.943512] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.965548] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1223.986050] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.016753] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.060618] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.134157] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.185527] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.341971] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.407221] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.440300] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.460761] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.526701] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.578849] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.597605] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.636816] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.677898] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.710593] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1224.739621] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.017730] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.118235] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.150194] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.407787] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.456756] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.472797] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.569270] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.604271] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.634725] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.796105] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1225.874808] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.000628] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.018279] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.098535] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.194945] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.207270] Lustre: lustre-OST0001-osc-ffff8ff38550e800: disconnect after 20s idle [ 1226.234225] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.258804] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.280522] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.455658] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.544842] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.701495] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.811586] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.831778] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.853267] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.953728] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1226.980820] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.015935] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.043808] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.062645] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.094043] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.138626] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.296734] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.497454] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.726160] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.747593] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.777884] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1227.999502] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.039584] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.070511] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.107021] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.165607] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.181302] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.217066] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.250387] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.582694] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.633079] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.663038] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.684161] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.710518] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.735577] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.776369] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.803040] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.836245] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.912068] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.938691] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1228.961322] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.018230] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.073289] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.142528] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.184918] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.231915] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.267176] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.339794] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.419900] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.457957] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.551553] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.579585] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.611861] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.944857] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.965918] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1229.995879] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1230.012322] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1230.038678] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1230.088144] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1230.105810] rw_seq_cst_vs_d (32936): drop_caches: 3 [ 1233.783664] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:37:46 (1773103066) [ 1237.055800] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:37:49 (1773103069) [ 1240.237141] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:37:52 (1773103072) [ 1251.719896] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:38:04 (1773103084) [ 1252.016334] bash (35423): drop_caches: 3 [ 1255.170357] bash (35423): drop_caches: 3 [ 1258.239330] bash (35423): drop_caches: 3 [ 1261.450240] bash (35423): drop_caches: 3 [ 1264.551648] bash (35423): drop_caches: 3 [ 1268.335971] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:38:20 (1773103100) [ 1273.724261] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:38:26 (1773103106) [ 1290.249641] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:38:42 (1773103122) [ 1293.705858] Lustre: DEBUG MARKER: loop 5 [ 1295.998416] Lustre: DEBUG MARKER: loop 10 [ 1298.259216] Lustre: DEBUG MARKER: loop 15 [ 1300.452412] Lustre: DEBUG MARKER: loop 20 [ 1303.815644] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:38:56 (1773103136) [ 1306.434374] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:38:58 (1773103138) [ 1309.269618] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:39:01 (1773103141) [ 1323.487203] Lustre: lustre-OST0001-osc-ffff8ff38655e800: disconnect after 21s idle [ 1328.608688] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 24s idle [ 1328.612549] Lustre: Skipped 1 previous similar message [ 1373.197134] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:40:05 (1773103205) [ 1375.768137] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:40:08 (1773103208) [ 1378.194677] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:40:10 (1773103210) [ 1380.602679] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:40:13 (1773103213) [ 1383.068705] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:40:15 (1773103215) [ 1386.233740] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:40:18 (1773103218) [ 1390.663142] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:40:23 (1773103223) [ 1394.381344] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1395.063593] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:40:27 (1773103227) [ 1399.664335] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:40:32 (1773103232) [ 1399.830465] Lustre: *** cfs_fail_loc=314, val=0*** [ 1400.864415] Lustre: *** cfs_fail_loc=314, val=0*** [ 1400.866268] Lustre: Skipped 2 previous similar messages [ 1403.172883] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:40:35 (1773103235) [ 1411.414606] Lustre: *** cfs_fail_loc=314, val=0*** [ 1411.415949] Lustre: Skipped 1 previous similar message [ 1411.441474] LustreError: lustre-OST0000-osc-ffff8ff38550e800: operation ldlm_enqueue to node 192.168.202.114@tcp failed: rc = -107 [ 1411.444561] Lustre: lustre-OST0000-osc-ffff8ff38550e800: Connection to lustre-OST0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1411.453545] LustreError: lustre-OST0000-osc-ffff8ff38550e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1411.458647] Lustre: 2388:0:(llite_lib.c:4151:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.114@tcp:/lustre/fid: [0x240000403:0x3:0x0]// may get corrupted (rc -108) [ 1411.465621] LustreError: 46474:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8ff38550e800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff8ff39ad2f300) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1411.472673] Lustre: lustre-OST0000-osc-ffff8ff38550e800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 1414.277565] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:40:46 (1773103246) [ 1414.381724] LustreError: 47063:0:(file.c:790:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1417.407156] LustreError: 47063:0:(file.c:790:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1419.526296] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:40:52 (1773103252) [ 1422.393394] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:40:54 (1773103254) [ 1425.633331] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1426.174393] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:40:58 (1773103258) [ 1426.746544] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1427.325477] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:40:59 (1773103259) [ 1431.009082] Lustre: lustre-MDT0000-mdc-ffff8ff38655e800: Connection to lustre-MDT0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1436.131096] LustreError: MGC192.168.202.114@tcp: Connection to MGS (at 192.168.202.114@tcp) was lost; in progress operations using this service will fail [ 1436.138815] Lustre: Evicted from MGS (at 192.168.202.114@tcp) after server handle changed from 0x29766bd1f7b1396f to 0x29766bd1f7c103f6 [ 1436.143069] Lustre: MGC192.168.202.114@tcp: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 1449.137359] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:41:21 (1773103281) [ 1466.847199] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 24s idle [ 1466.849453] Lustre: Skipped 1 previous similar message [ 1468.796858] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:41:41 (1773103301) [ 1474.421366] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:41:46 (1773103306) [ 1527.222212] Lustre: lustre-OST0000-osc-ffff8ff38655e800: Connection to lustre-OST0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1527.227854] Lustre: Skipped 1 previous similar message [ 1527.231201] LustreError: lustre-OST0000-osc-ffff8ff38550e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1527.235067] LustreError: lustre-OST0000-osc-ffff8ff38655e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1527.235206] Lustre: lustre-OST0000-osc-ffff8ff38550e800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 1527.242973] Lustre: Skipped 3 previous similar messages [ 1537.461915] Lustre: lustre-OST0001-osc-ffff8ff38655e800: Connection to lustre-OST0001 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1537.467044] Lustre: Skipped 1 previous similar message [ 1537.472972] LustreError: lustre-OST0001-osc-ffff8ff38655e800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1537.477659] Lustre: lustre-OST0001-osc-ffff8ff38655e800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 1548.768272] Lustre: lustre-OST0000-osc-ffff8ff38655e800: disconnect after 21s idle [ 1549.943565] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1550.487285] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid in IDLE state after 0 sec [ 1551.892025] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1552.473048] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid in FULL state after 0 sec [ 1554.299842] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1554.803822] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid in IDLE state after 0 sec [ 1555.987857] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1556.502198] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid in FULL state after 0 sec [ 1559.803754] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1560.353702] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ff38550e800.ost_server_uuid in IDLE state after 0 sec [ 1561.662459] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid 50 [ 1562.212167] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ff38550e800.ost_server_uuid in FULL state after 0 sec [ 1562.780960] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:43:15 (1773103395) [ 1563.736846] Lustre: DEBUG MARKER: Race attempt 0 [ 1565.271141] Lustre: DEBUG MARKER: Wait for 58397 58532 for 60 sec... [ 1627.869142] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:44:20 (1773103460) [ 1633.232425] Lustre: DEBUG MARKER: start test - cycle (0) [ 1648.446469] Lustre: DEBUG MARKER: start test - cycle (1) [ 1663.589588] Lustre: DEBUG MARKER: start test - cycle (2) [ 1678.619901] Lustre: DEBUG MARKER: start test - cycle (3) [ 1694.972768] Lustre: DEBUG MARKER: start test - cycle (4) [ 1710.457383] Lustre: DEBUG MARKER: start test - cycle (5) [ 1724.920537] Lustre: DEBUG MARKER: start test - cycle (6) [ 1741.318760] Lustre: DEBUG MARKER: start test - cycle (7) [ 1759.901126] Lustre: DEBUG MARKER: start test - cycle (8) [ 1777.301646] Lustre: DEBUG MARKER: start test - cycle (9) [ 1794.929159] Lustre: DEBUG MARKER: start test - cycle (10) [ 1813.368942] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:47:25 (1773103645) [ 1825.247206] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 23s idle [ 1825.250839] Lustre: Skipped 2 previous similar messages [ 1834.473358] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:47:46 (1773103666) [ 1837.283033] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:47:49 (1773103669) [ 1840.875795] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:47:53 (1773103673) [ 1844.326968] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:47:56 (1773103676) [ 1844.449473] Lustre: *** cfs_fail_loc=411, val=0*** [ 1846.802949] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1847.470980] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:47:59 (1773103679) [ 1856.470399] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:48:08 (1773103688) [ 1864.739794] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:48:17 (1773103697) [ 1872.976749] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:48:25 (1773103705) [ 1881.165259] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:48:33 (1773103713) [ 1886.508914] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:48:39 (1773103719) [ 1891.974713] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:48:44 (1773103724) [ 1897.510625] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:48:50 (1773103730) [ 1902.915951] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:48:55 (1773103735) [ 1908.472537] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:49:00 (1773103740) [ 1913.918366] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:49:06 (1773103746) [ 1919.435641] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:49:11 (1773103751) [ 1925.063228] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:49:17 (1773103757) [ 1943.007233] Lustre: lustre-OST0000-osc-ffff8ff38655e800: disconnect after 25s idle [ 1943.011651] Lustre: Skipped 2 previous similar messages [ 2547.167211] Lustre: lustre-OST0000-osc-ffff8ff38550e800: disconnect after 24s idle [ 2547.169479] Lustre: Skipped 1 previous similar message [ 2650.784711] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 21:01:23 (1773104483) [ 2655.794278] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 21:01:28 (1773104488) [ 2660.563906] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 21:01:33 (1773104493) [ 2665.428558] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 21:01:38 (1773104498) [ 2670.371479] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 21:01:42 (1773104502) [ 2675.226951] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 21:01:47 (1773104507) [ 2680.151409] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 21:01:52 (1773104512) [ 2685.325518] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 21:01:57 (1773104517) [ 2690.362221] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 21:02:02 (1773104522) [ 2711.715681] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 21:02:24 (1773104544) [ 2717.133564] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 21:02:29 (1773104549) [ 2722.469795] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 21:02:35 (1773104555) [ 2727.569435] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 21:02:40 (1773104560) [ 2732.765887] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 21:02:45 (1773104565) [ 2737.830704] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 21:02:50 (1773104570) [ 2743.168265] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 21:02:55 (1773104575) [ 2748.665146] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 21:03:01 (1773104581) [ 2753.970491] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 21:03:06 (1773104586) [ 2792.620728] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 21:03:45 (1773104625) [ 2885.087331] Lustre: lustre-OST0001-osc-ffff8ff38655e800: disconnect after 24s idle [ 2885.090776] Lustre: Skipped 5 previous similar messages [ 3233.662337] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:11:06 (1773105066) [ 3238.882225] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:11:11 (1773105071) [ 3244.034671] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:11:16 (1773105076) [ 3249.351492] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:11:21 (1773105081) [ 3254.700686] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:11:27 (1773105087) [ 3259.926424] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:11:32 (1773105092) [ 3265.334881] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:11:37 (1773105097) [ 3270.579523] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:11:43 (1773105103) [ 3275.884830] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:11:48 (1773105108) [ 3281.086395] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:11:53 (1773105113) [ 3319.131618] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:12:31 (1773105151) [ 3324.435863] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:12:36 (1773105156) [ 3329.634231] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:12:42 (1773105162) [ 3334.786766] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:12:47 (1773105167) [ 3340.173587] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:12:52 (1773105172) [ 3345.603836] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:12:58 (1773105178) [ 3350.373383] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:13:03 (1773105183) [ 3354.965955] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:13:07 (1773105187) [ 3360.330492] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:13:12 (1773105192) [ 3796.301938] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 21:20:28 (1773105628) [ 3801.609790] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 21:20:34 (1773105634) [ 3807.133395] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 21:20:39 (1773105639) [ 3812.090872] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 21:20:44 (1773105644) [ 3816.970962] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 21:20:49 (1773105649) [ 3821.831970] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 21:20:54 (1773105654) [ 3826.603952] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 21:20:59 (1773105659) [ 3831.419979] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 21:21:04 (1773105664) [ 3836.266358] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 21:21:08 (1773105668) [ 3841.148846] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 21:21:13 (1773105673) [ 3846.046827] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 21:21:18 (1773105678) [ 3851.859476] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 21:21:24 (1773105684) [ 3856.902482] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 21:21:29 (1773105689) [ 3861.684459] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 21:21:34 (1773105694) [ 3866.477208] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 21:21:39 (1773105699) [ 3871.308727] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 21:21:43 (1773105703) [ 3877.133692] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 21:21:49 (1773105709) [ 3877.208513] LustreError: 6131:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3879.287079] LustreError: 6131:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3884.166424] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 21:21:56 (1773105716) [ 3888.170592] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 21:22:00 (1773105720) [ 3888.242480] LustreError: 240063:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3892.303070] LustreError: 240063:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3892.310565] LustreError: 240063:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3896.367071] LustreError: 240063:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3896.382209] LustreError: 240070:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3900.439092] LustreError: 240070:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3902.444824] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 21:22:15 (1773105735) [ 3908.962133] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 21:22:21 (1773105741) [ 3912.004581] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 21:22:24 (1773105744) [ 3915.971769] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 21:22:28 (1773105748) [ 3924.447182] Lustre: lustre-OST0001-osc-ffff8ff38655e800: disconnect after 22s idle [ 3924.449216] Lustre: Skipped 2 previous similar messages [ 3939.822033] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 21:22:52 (1773105772) [ 3947.232818] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 21:22:59 (1773105779) [ 3954.752329] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 21:23:07 (1773105787) [ 3967.489361] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 21:23:20 (1773105800) [ 3976.893131] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 21:23:29 (1773105809) [ 3989.511118] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 21:23:42 (1773105822) [ 4002.488948] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 21:23:55 (1773105835) [ 4016.368772] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 21:24:08 (1773105848) [ 4023.588064] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 21:24:16 (1773105856) [ 4055.908580] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 21:24:48 (1773105888) [ 4059.357794] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4062.516996] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 21:24:54 (1773105894) [ 4065.919687] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 21:24:58 (1773105898) [ 4068.469576] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 21:25:01 (1773105901) [ 4070.439676] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 21:25:03 (1773105903) [ 4079.895804] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 21:25:12 (1773105912) [ 4093.512727] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 21:25:26 (1773105926) [ 4095.539860] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 21:25:28 (1773105928) [ 4097.651180] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 21:25:30 (1773105930) [ 4100.717589] LustreError: lustre-MDT0000-mdc-ffff8ff38655e800: operation ldlm_enqueue to node 192.168.202.114@tcp failed: rc = -35 [ 4100.721570] LustreError: Skipped 1 previous similar message [ 4103.607235] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 21:25:36 (1773105936) [ 4103.742739] LustreError: 2391:0:(osc_request.c:3132:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4105.823135] LustreError: 2391:0:(osc_request.c:3132:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4110.656313] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 21:25:43 (1773105943) [ 4155.931888] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 21:26:28 (1773105988) [ 4158.781575] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 21:26:31 (1773105991) [ 4162.668639] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 21:26:35 (1773105995) [ 4167.227573] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 21:26:39 (1773105999) [ 4171.881939] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 21:26:44 (1773106004) [ 4179.017125] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 21:26:51 (1773106011) [ 4186.484091] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 21:26:59 (1773106019) [ 4189.812070] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 21:27:02 (1773106022) [ 4193.573854] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 21:27:06 (1773106026) [ 4200.688742] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 21:27:13 (1773106033) [ 4244.445801] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 21:27:57 (1773106077) [ 4357.862757] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:29:50 (1773106190) [ 4469.147366] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:31:41 (1773106301) [ 4559.327176] Lustre: lustre-OST0001-osc-ffff8ff38655e800: disconnect after 24s idle [ 4559.329548] Lustre: Skipped 9 previous similar messages [ 4731.272768] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:36:03 (1773106563) [ 4758.570242] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:36:31 (1773106591) [ 4789.977574] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:37:02 (1773106622) [ 4809.441841] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:37:22 (1773106642) [ 4871.146002] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:38:23 (1773106703) [ 4925.538134] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:39:18 (1773106758) [ 5013.880673] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:40:46 (1773106846) [ 5014.960374] LustreError: 286928:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38655e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5014.987279] Lustre: Unmounted lustre-client [ 5015.888469] LustreError: 286941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38550e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5015.891721] LustreError: 286941:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5015.917089] Lustre: Unmounted lustre-client [ 5073.038206] Lustre: Mounted lustre-client [ 5074.534635] Lustre: Mounted lustre-client [ 5075.468192] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5140.692908] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 21:42:53 (1773106973) [ 5148.699066] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 21:43:01 (1773106981) [ 5152.146238] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 21:43:04 (1773106984) [ 5202.545667] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 21:43:55 (1773107035) [ 5247.652888] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 21:44:40 (1773107080) [ 5248.991178] Lustre: lustre-OST0001-osc-ffff8ff382f58000: disconnect after 21s idle [ 5248.993809] Lustre: Skipped 13 previous similar messages [ 5251.235454] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 21:44:43 (1773107083) [ 5289.826484] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 21:45:22 (1773107122) [ 5302.157806] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 21:45:34 (1773107134) [ 5343.208435] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 21:46:15 (1773107175) [ 5346.634990] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 21:46:19 (1773107179) [ 5359.560889] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 21:46:32 (1773107192) [ 5363.891327] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 21:46:36 (1773107196) [ 5364.307889] LustreError: 307676:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x398:0x0]: rc = -5 [ 5364.311490] LustreError: 307676:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5364.848268] LustreError: 307707:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x39b:0x0]: rc = -5 [ 5364.854610] LustreError: 307707:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 7 previous similar messages [ 5364.859611] LustreError: 307707:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5364.864297] LustreError: 307707:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 7 previous similar messages [ 5365.851667] LustreError: 307754:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3a7:0x0]: rc = -5 [ 5365.857683] LustreError: 307754:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 5365.861797] LustreError: 307754:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5365.866664] LustreError: 307754:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 11 previous similar messages [ 5367.854504] LustreError: 307887:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x78:0x0]: rc = -5 [ 5367.858313] LustreError: 307887:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 38 previous similar messages [ 5367.862919] LustreError: 307887:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5367.865101] LustreError: 307887:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 38 previous similar messages [ 5371.918280] LustreError: 308233:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xcf:0x0]: rc = -5 [ 5371.922179] LustreError: 308233:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 101 previous similar messages [ 5371.925154] LustreError: 308233:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5371.926941] LustreError: 308233:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 101 previous similar messages [ 5379.973272] LustreError: 308803:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x19e:0x0]: rc = -5 [ 5379.976117] LustreError: 308803:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 180 previous similar messages [ 5379.978320] LustreError: 308803:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5379.980381] LustreError: 308803:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 180 previous similar messages [ 5396.054659] LustreError: 310219:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x3d7:0x0]: rc = -5 [ 5396.058741] LustreError: 310219:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 555 previous similar messages [ 5396.061432] LustreError: 310219:0:(llite_lib.c:3706:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5396.063383] LustreError: 310219:0:(llite_lib.c:3706:ll_prep_inode()) Skipped 555 previous similar messages [ 5413.815601] LustreError: 311711:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8ff387205800: inode [0x2000013a1:0x81f:0x0] mdc close failed: rc = -2 [ 5426.819260] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 21:47:39 (1773107259) [ 5429.297894] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 21:47:41 (1773107261) [ 5486.146091] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 21:48:38 (1773107318) [ 5486.637730] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5487.195904] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 21:48:39 (1773107319) [ 5531.815799] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 21:49:24 (1773107364) [ 5534.342559] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 21:49:26 (1773107366) [ 5657.308405] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 21:51:29 (1773107489) [ 5665.050595] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 21:51:37 (1773107497) [ 5668.352334] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 21:51:40 (1773107500) [ 5850.443175] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 21:54:43 (1773107683) [ 5986.271152] Lustre: lustre-OST0000-osc-ffff8ff382f58000: disconnect after 21s idle [ 5986.273315] Lustre: Skipped 3 previous similar messages [ 6033.150945] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 21:57:45 (1773107865) [ 6036.223784] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 21:57:48 (1773107868) [ 6045.738320] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 21:57:58 (1773107878) [ 6045.802744] Lustre: DEBUG MARKER: write [ 6045.825353] LustreError: 289340:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6047.834197] Lustre: DEBUG MARKER: kill 369977 [ 6047.836728] LustreError: 369977:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6050.927105] LustreError: 289340:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6053.871132] LustreError: 369977:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6056.000075] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 21:58:08 (1773107888) [ 6058.170879] LustreError: 370590:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6068.263120] LustreError: 370590:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6071.387826] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 21:58:23 (1773107903) [ 6071.591373] LustreError: 371178:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6073.679146] LustreError: 371178:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 6079.901374] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 21:58:32 (1773107912) [ 6080.491819] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6081.150218] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 21:58:33 (1773107913) [ 6083.870092] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 21:58:36 (1773107916) [ 6086.508603] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 21:58:39 (1773107919) [ 6089.144530] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 21:58:41 (1773107921) [ 6091.911890] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 21:58:44 (1773107924) [ 6094.462462] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 21:58:47 (1773107927) [ 6096.952624] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 21:58:49 (1773107929) [ 6100.284521] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 21:58:52 (1773107932) [ 6103.782807] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 21:58:56 (1773107936) [ 6104.664407] Lustre: *** cfs_fail_loc=415, val=0*** [ 6111.864923] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 21:59:04 (1773107944) [ 6131.358035] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 21:59:23 (1773107963) [ 6131.463895] LustreError: 303107:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6131.466551] LustreError: 303107:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6136.559088] LustreError: 289186:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6136.562944] LustreError: 289186:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6146.759227] LustreError: 303107:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6146.764101] LustreError: 303107:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 6155.255073] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 21:59:47 (1773107987) [ 6158.864438] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 21:59:51 (1773107991) [ 6161.914954] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 21:59:54 (1773107994) [ 6165.045872] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 21:59:57 (1773107997) [ 6169.230197] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:00:01 (1773108001) [ 6177.632805] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:00:10 (1773108010) [ 6177.779911] LustreError: 381910:0:(osc_request.c:2983:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6177.782272] LustreError: 381910:0:(osc_request.c:2983:osc_build_rpc()) Skipped 6 previous similar messages [ 6181.839098] LustreError: 381910:0:(osc_request.c:2983:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6181.841030] LustreError: 381910:0:(osc_request.c:2983:osc_build_rpc()) Skipped 1 previous similar message [ 6183.908487] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:00:16 (1773108016) [ 6185.400985] LustreError: 382619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff382f58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6185.403586] LustreError: 382619:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6185.438092] Lustre: Unmounted lustre-client [ 6185.931709] LustreError: 382639:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff387205800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6185.934508] LustreError: 382639:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6186.026461] Lustre: Unmounted lustre-client [ 6186.508183] Lustre: DEBUG MARKER: Iteration 1 [ 6186.617560] LustreError: 382802:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6186.618853] LustreError: 382803:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6186.621635] LustreError: 382802:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6186.676588] Lustre: Mounted lustre-client [ 6187.239148] LustreError: 382913:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff398936000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6187.241721] LustreError: 382913:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 6187.309760] Lustre: Unmounted lustre-client [ 6188.255688] Key type lgssc unregistered [ 6188.369872] LNet: 383156:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6188.372285] LNetError: 383156:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6188.380704] LNet: Removed LNI 192.168.202.14@tcp [ 6188.664110] Key type .llcrypt unregistered [ 6188.665852] Key type ._llcrypt unregistered [ 6189.027336] Key type ._llcrypt registered [ 6189.028249] Key type .llcrypt registered [ 6189.283777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6189.288687] alg: No test for adler32 (adler32-zlib) [ 6190.251610] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6190.511552] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6192.119178] Key type lgssc registered [ 6192.623462] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6197.527706] Lustre: DEBUG MARKER: Iteration 2 [ 6197.659259] LustreError: 383986:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6197.659267] LustreError: 383988:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6197.663277] LustreError: 383986:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6198.715680] Lustre: Mounted lustre-client [ 6199.140540] LustreError: 384103:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff391267800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6199.143614] LustreError: 384103:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6199.220994] Lustre: Unmounted lustre-client [ 6200.531064] Key type lgssc unregistered [ 6200.670834] LNet: 384347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6200.675876] LNetError: 384347:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6200.684400] LNet: Removed LNI 192.168.202.14@tcp [ 6200.991099] Key type .llcrypt unregistered [ 6200.992456] Key type ._llcrypt unregistered [ 6201.276120] Key type ._llcrypt registered [ 6201.277485] Key type .llcrypt registered [ 6201.525344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6201.530224] alg: No test for adler32 (adler32-zlib) [ 6202.405787] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6202.502483] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6204.095189] Key type lgssc registered [ 6204.749256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6208.927631] Lustre: DEBUG MARKER: Iteration 3 [ 6209.063282] LustreError: 385178:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6209.063316] LustreError: 385179:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6209.069690] LustreError: 385178:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6210.117248] Lustre: Mounted lustre-client [ 6210.118640] Lustre: Skipped 1 previous similar message [ 6210.533973] LustreError: 385291:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b7ed4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6210.538683] LustreError: 385291:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6210.616064] Lustre: Unmounted lustre-client [ 6211.833918] Key type lgssc unregistered [ 6211.984691] LNet: 385535:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6211.987173] LNetError: 385535:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6211.996428] LNet: Removed LNI 192.168.202.14@tcp [ 6212.202094] Key type .llcrypt unregistered [ 6212.203276] Key type ._llcrypt unregistered [ 6212.412197] Key type ._llcrypt registered [ 6212.413241] Key type .llcrypt registered [ 6212.637792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6212.642207] alg: No test for adler32 (adler32-zlib) [ 6213.484923] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6213.559808] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6215.135106] Key type lgssc registered [ 6215.447693] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6218.793566] Lustre: DEBUG MARKER: Iteration 4 [ 6218.895828] LustreError: 386366:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6218.896091] LustreError: 386367:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6218.899755] LustreError: 386366:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6219.952599] Lustre: Mounted lustre-client [ 6219.953545] Lustre: Skipped 1 previous similar message [ 6220.381314] LustreError: 386477:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b97c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6220.385509] LustreError: 386477:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6220.460060] Lustre: Unmounted lustre-client [ 6221.293175] Key type lgssc unregistered [ 6221.402414] LNet: 386721:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6221.404516] LNetError: 386721:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6221.410772] LNet: Removed LNI 192.168.202.14@tcp [ 6221.608167] Key type .llcrypt unregistered [ 6221.609118] Key type ._llcrypt unregistered [ 6221.853253] Key type ._llcrypt registered [ 6221.856468] Key type .llcrypt registered [ 6222.022841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6222.028386] alg: No test for adler32 (adler32-zlib) [ 6222.870073] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6222.945579] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6224.519116] Key type lgssc registered [ 6224.864027] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6228.436391] Lustre: DEBUG MARKER: Iteration 5 [ 6228.543201] LustreError: 387552:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6228.543241] LustreError: 387553:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6228.547201] LustreError: 387552:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6229.604695] Lustre: Mounted lustre-client [ 6230.038794] LustreError: 387676:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b12d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6230.093446] Lustre: Unmounted lustre-client [ 6231.005373] Key type lgssc unregistered [ 6231.112459] LNet: 387920:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6231.115271] LNetError: 387920:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6231.125723] LNet: Removed LNI 192.168.202.14@tcp [ 6231.349103] Key type .llcrypt unregistered [ 6231.351094] Key type ._llcrypt unregistered [ 6231.595275] Key type ._llcrypt registered [ 6231.596486] Key type .llcrypt registered [ 6231.816805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6231.821701] alg: No test for adler32 (adler32-zlib) [ 6232.685617] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6232.760584] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6234.335131] Key type lgssc registered [ 6234.654303] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6238.560976] Lustre: DEBUG MARKER: Iteration 6 [ 6238.677132] LustreError: 388752:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6238.678188] LustreError: 388753:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6238.681678] LustreError: 388752:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6239.733162] Lustre: Mounted lustre-client [ 6240.169818] LustreError: 388865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b12d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6240.173669] LustreError: 388865:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6240.242352] Lustre: Unmounted lustre-client [ 6240.243381] Lustre: Skipped 1 previous similar message [ 6241.289071] Key type lgssc unregistered [ 6241.400457] LNet: 389108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6241.404299] LNetError: 389108:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6241.415535] LNet: Removed LNI 192.168.202.14@tcp [ 6241.738094] Key type .llcrypt unregistered [ 6241.739099] Key type ._llcrypt unregistered [ 6241.987775] Key type ._llcrypt registered [ 6241.988931] Key type .llcrypt registered [ 6242.165392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6242.169805] alg: No test for adler32 (adler32-zlib) [ 6243.018925] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6243.102471] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6244.687154] Key type lgssc registered [ 6245.179461] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6249.220542] Lustre: DEBUG MARKER: Iteration 7 [ 6249.365384] LustreError: 389938:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6249.365457] LustreError: 389940:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6249.370100] LustreError: 389938:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6250.433669] Lustre: Mounted lustre-client [ 6250.434694] Lustre: Skipped 1 previous similar message [ 6250.959157] LustreError: 390053:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38655f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6250.962068] LustreError: 390053:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6251.026175] Lustre: Unmounted lustre-client [ 6252.352496] Key type lgssc unregistered [ 6252.486658] LNet: 390297:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6252.490269] LNetError: 390297:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6252.498169] LNet: Removed LNI 192.168.202.14@tcp [ 6252.778101] Key type .llcrypt unregistered [ 6252.779757] Key type ._llcrypt unregistered [ 6253.036092] Key type ._llcrypt registered [ 6253.037326] Key type .llcrypt registered [ 6253.216589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6253.221062] alg: No test for adler32 (adler32-zlib) [ 6254.080792] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6254.162605] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6255.743177] Key type lgssc registered [ 6256.201032] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6260.030626] Lustre: DEBUG MARKER: Iteration 8 [ 6260.130866] LustreError: 391128:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6260.130943] LustreError: 391129:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6260.134992] LustreError: 391128:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6261.197698] Lustre: Mounted lustre-client [ 6261.199537] Lustre: Skipped 1 previous similar message [ 6261.608996] LustreError: 391240:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff387829000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6261.612596] LustreError: 391240:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6261.662081] Lustre: Unmounted lustre-client [ 6262.486829] Key type lgssc unregistered [ 6262.597288] LNet: 391484:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6262.599450] LNetError: 391484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6262.611401] LNet: Removed LNI 192.168.202.14@tcp [ 6262.809089] Key type .llcrypt unregistered [ 6262.810177] Key type ._llcrypt unregistered [ 6263.059222] Key type ._llcrypt registered [ 6263.060276] Key type .llcrypt registered [ 6263.253540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6263.258030] alg: No test for adler32 (adler32-zlib) [ 6264.126105] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6264.206861] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6265.791130] Key type lgssc registered [ 6266.268335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6269.956239] Lustre: DEBUG MARKER: Iteration 9 [ 6270.084060] LustreError: 392315:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6270.084851] LustreError: 392316:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6270.090064] LustreError: 392315:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6271.155071] Lustre: Mounted lustre-client [ 6271.602413] LustreError: 392430:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38d2a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6271.605423] LustreError: 392430:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6271.685459] Lustre: Unmounted lustre-client [ 6272.524745] Key type lgssc unregistered [ 6272.632419] LNet: 392674:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6272.635150] LNetError: 392674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6272.641718] LNet: Removed LNI 192.168.202.14@tcp [ 6272.858088] Key type .llcrypt unregistered [ 6272.859098] Key type ._llcrypt unregistered [ 6273.082901] Key type ._llcrypt registered [ 6273.084201] Key type .llcrypt registered [ 6273.273814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6273.278177] alg: No test for adler32 (adler32-zlib) [ 6274.121320] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6274.202935] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6275.791158] Key type lgssc registered [ 6276.305695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6280.440673] Lustre: DEBUG MARKER: Iteration 10 [ 6280.557531] LustreError: 393505:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6280.557846] LustreError: 393506:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6280.561970] LustreError: 393505:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6281.631050] Lustre: Mounted lustre-client [ 6281.632825] Lustre: Skipped 1 previous similar message [ 6282.182632] LustreError: 393619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39838a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6282.186270] LustreError: 393619:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6282.314150] Lustre: Unmounted lustre-client [ 6283.297974] Key type lgssc unregistered [ 6283.401710] LNet: 393863:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6283.403871] LNetError: 393863:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6283.413262] LNet: Removed LNI 192.168.202.14@tcp [ 6283.632095] Key type .llcrypt unregistered [ 6283.633131] Key type ._llcrypt unregistered [ 6283.948330] Key type ._llcrypt registered [ 6283.949620] Key type .llcrypt registered [ 6284.144623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6284.148994] alg: No test for adler32 (adler32-zlib) [ 6285.012922] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6285.103707] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6286.687147] Key type lgssc registered [ 6287.225565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6290.884546] Lustre: DEBUG MARKER: Iteration 11 [ 6290.997381] LustreError: 394695:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6290.997438] LustreError: 394696:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6291.001764] LustreError: 394695:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6292.059589] Lustre: Mounted lustre-client [ 6292.060552] Lustre: Skipped 1 previous similar message [ 6292.483727] LustreError: 394801:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38db39000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6292.486941] LustreError: 394801:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6292.580915] Lustre: Unmounted lustre-client [ 6293.446371] Key type lgssc unregistered [ 6293.550495] LNet: 395044:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6293.552746] LNetError: 395044:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6293.561684] LNet: Removed LNI 192.168.202.14@tcp [ 6293.759094] Key type .llcrypt unregistered [ 6293.760087] Key type ._llcrypt unregistered [ 6294.020314] Key type ._llcrypt registered [ 6294.021381] Key type .llcrypt registered [ 6294.185315] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6294.189829] alg: No test for adler32 (adler32-zlib) [ 6295.051446] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6295.136202] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6296.719129] Key type lgssc registered [ 6297.200231] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6300.671090] Lustre: DEBUG MARKER: Iteration 12 [ 6300.764548] LustreError: 395875:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6300.764578] LustreError: 395876:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6300.768474] LustreError: 395875:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6301.829012] Lustre: Mounted lustre-client [ 6301.830088] Lustre: Skipped 1 previous similar message [ 6302.268533] LustreError: 395987:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b5dff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6302.271975] LustreError: 395987:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6302.321499] Lustre: Unmounted lustre-client [ 6303.287523] Key type lgssc unregistered [ 6303.395498] LNet: 396232:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6303.397705] LNetError: 396232:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6303.407615] LNet: Removed LNI 192.168.202.14@tcp [ 6303.620100] Key type .llcrypt unregistered [ 6303.621062] Key type ._llcrypt unregistered [ 6303.862324] Key type ._llcrypt registered [ 6303.869766] Key type .llcrypt registered [ 6304.043741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6304.048372] alg: No test for adler32 (adler32-zlib) [ 6304.890671] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6304.962844] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6306.535103] Key type lgssc registered [ 6306.847224] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6310.260692] Lustre: DEBUG MARKER: Iteration 13 [ 6310.363615] LustreError: 397063:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6310.363943] LustreError: 397064:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6310.367421] LustreError: 397063:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6311.423339] Lustre: Mounted lustre-client [ 6311.424409] Lustre: Skipped 1 previous similar message [ 6311.855213] LustreError: 397174:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39c47b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6311.859767] LustreError: 397174:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6311.924133] Lustre: Unmounted lustre-client [ 6312.734686] Key type lgssc unregistered [ 6312.845501] LNet: 397418:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6312.847729] LNetError: 397418:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6312.856722] LNet: Removed LNI 192.168.202.14@tcp [ 6313.080104] Key type .llcrypt unregistered [ 6313.081461] Key type ._llcrypt unregistered [ 6313.333384] Key type ._llcrypt registered [ 6313.336400] Key type .llcrypt registered [ 6313.529668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6313.534472] alg: No test for adler32 (adler32-zlib) [ 6314.384193] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6314.459686] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6316.039112] Key type lgssc registered [ 6316.369048] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6319.955333] Lustre: DEBUG MARKER: Iteration 14 [ 6320.075979] LustreError: 398250:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6320.076089] LustreError: 398249:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6320.082166] LustreError: 398250:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6321.161459] Lustre: Mounted lustre-client [ 6321.662407] LustreError: 398361:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b96d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6321.665591] LustreError: 398361:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6321.749667] Lustre: Unmounted lustre-client [ 6322.833568] Key type lgssc unregistered [ 6322.951852] LNet: 398604:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6322.956742] LNetError: 398604:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6322.968518] LNet: Removed LNI 192.168.202.14@tcp [ 6323.221118] Key type .llcrypt unregistered [ 6323.222247] Key type ._llcrypt unregistered [ 6323.500367] Key type ._llcrypt registered [ 6323.501442] Key type .llcrypt registered [ 6323.743657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6323.748625] alg: No test for adler32 (adler32-zlib) [ 6324.600453] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6324.686852] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6326.271129] Key type lgssc registered [ 6326.675118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6330.424466] Lustre: DEBUG MARKER: Iteration 15 [ 6330.539427] LustreError: 399436:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6330.539449] LustreError: 399437:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6330.544916] LustreError: 399436:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6331.605285] Lustre: Mounted lustre-client [ 6332.119732] LustreError: 399546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff382f5f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6332.188365] Lustre: Unmounted lustre-client [ 6332.189735] Lustre: Skipped 1 previous similar message [ 6333.120747] Key type lgssc unregistered [ 6333.240743] LNet: 399794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6333.243767] LNetError: 399794:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6333.254581] LNet: Removed LNI 192.168.202.14@tcp [ 6333.485153] Key type .llcrypt unregistered [ 6333.486543] Key type ._llcrypt unregistered [ 6333.735415] Key type ._llcrypt registered [ 6333.737057] Key type .llcrypt registered [ 6333.958878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6333.971357] alg: No test for adler32 (adler32-zlib) [ 6334.824215] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6334.901342] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6336.479181] Key type lgssc registered [ 6336.866755] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6340.408801] Lustre: DEBUG MARKER: Iteration 16 [ 6340.512476] LustreError: 400624:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6340.512694] LustreError: 400625:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6340.517097] LustreError: 400624:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6341.570242] Lustre: Mounted lustre-client [ 6341.994640] LustreError: 400737:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff388ef4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6341.997671] LustreError: 400737:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6342.120487] Lustre: Unmounted lustre-client [ 6342.941777] Key type lgssc unregistered [ 6343.050712] LNet: 400980:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6343.052740] LNetError: 400980:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6343.061689] LNet: Removed LNI 192.168.202.14@tcp [ 6343.263108] Key type .llcrypt unregistered [ 6343.264075] Key type ._llcrypt unregistered [ 6343.520463] Key type ._llcrypt registered [ 6343.524988] Key type .llcrypt registered [ 6343.684848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6343.689673] alg: No test for adler32 (adler32-zlib) [ 6344.536313] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6344.610429] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6346.183097] Key type lgssc registered [ 6346.511634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6350.077174] Lustre: DEBUG MARKER: Iteration 17 [ 6350.196917] LustreError: 401813:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6350.197073] LustreError: 401812:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6350.202699] LustreError: 401813:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6351.262706] Lustre: Mounted lustre-client [ 6351.264333] Lustre: Skipped 1 previous similar message [ 6351.710378] LustreError: 401925:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39c323800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6351.713936] LustreError: 401925:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6351.785504] Lustre: Unmounted lustre-client [ 6352.648301] Key type lgssc unregistered [ 6352.766432] LNet: 402169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6352.768664] LNetError: 402169:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6352.781296] LNet: Removed LNI 192.168.202.14@tcp [ 6353.036088] Key type .llcrypt unregistered [ 6353.038051] Key type ._llcrypt unregistered [ 6353.381182] Key type ._llcrypt registered [ 6353.382616] Key type .llcrypt registered [ 6353.577338] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6353.582245] alg: No test for adler32 (adler32-zlib) [ 6354.502363] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6354.608762] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6356.223175] Key type lgssc registered [ 6356.683989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6360.342827] Lustre: DEBUG MARKER: Iteration 18 [ 6360.444422] LustreError: 403002:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6360.444454] LustreError: 403003:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6360.448499] LustreError: 403002:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6361.496598] Lustre: Mounted lustre-client [ 6361.498285] Lustre: Skipped 1 previous similar message [ 6361.911431] LustreError: 403112:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3c070a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6361.978982] Lustre: Unmounted lustre-client [ 6361.980021] Lustre: Skipped 1 previous similar message [ 6362.815034] Key type lgssc unregistered [ 6362.922348] LNet: 403357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6362.924519] LNetError: 403357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6362.930728] LNet: Removed LNI 192.168.202.14@tcp [ 6363.137099] Key type .llcrypt unregistered [ 6363.138338] Key type ._llcrypt unregistered [ 6363.380876] Key type ._llcrypt registered [ 6363.386691] Key type .llcrypt registered [ 6363.555581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6363.565923] alg: No test for adler32 (adler32-zlib) [ 6364.413700] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6364.488898] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6366.071131] Key type lgssc registered [ 6366.435699] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6369.863143] Lustre: DEBUG MARKER: Iteration 19 [ 6369.965280] LustreError: 404190:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6369.965329] LustreError: 404191:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6369.969083] LustreError: 404190:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6371.027263] Lustre: Mounted lustre-client [ 6371.028666] Lustre: Skipped 1 previous similar message [ 6371.458990] LustreError: 404299:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b2b52800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6371.537567] Lustre: Unmounted lustre-client [ 6371.538598] Lustre: Skipped 1 previous similar message [ 6372.346737] Key type lgssc unregistered [ 6372.456446] LNet: 404545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6372.458813] LNetError: 404545:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6372.465724] LNet: Removed LNI 192.168.202.14@tcp [ 6372.674082] Key type .llcrypt unregistered [ 6372.675151] Key type ._llcrypt unregistered [ 6372.923702] Key type ._llcrypt registered [ 6372.927349] Key type .llcrypt registered [ 6373.114104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6373.119898] alg: No test for adler32 (adler32-zlib) [ 6373.970499] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6374.049815] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6375.631112] Key type lgssc registered [ 6375.958922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6379.241651] Lustre: DEBUG MARKER: Iteration 20 [ 6379.361660] LustreError: 405376:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6379.361721] LustreError: 405377:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6379.366585] LustreError: 405376:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6380.429398] Lustre: Mounted lustre-client [ 6380.431115] Lustre: Skipped 1 previous similar message [ 6380.996312] LustreError: 405484:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b0f9d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6380.999726] LustreError: 405484:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6381.055148] Lustre: Unmounted lustre-client [ 6381.967568] Key type lgssc unregistered [ 6382.084777] LNet: 405729:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6382.087066] LNetError: 405729:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6382.096221] LNet: Removed LNI 192.168.202.14@tcp [ 6382.335089] Key type .llcrypt unregistered [ 6382.336166] Key type ._llcrypt unregistered [ 6382.630763] Key type ._llcrypt registered [ 6382.638073] Key type .llcrypt registered [ 6382.878513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6382.883373] alg: No test for adler32 (adler32-zlib) [ 6383.749203] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6383.836037] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6385.423105] Key type lgssc registered [ 6385.791284] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6389.450383] Lustre: DEBUG MARKER: Iteration 21 [ 6389.566546] LustreError: 406561:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6389.566650] LustreError: 406562:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6389.570673] LustreError: 406561:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6390.621256] Lustre: Mounted lustre-client [ 6391.048508] LustreError: 406674:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff396c28800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6391.051622] LustreError: 406674:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6391.127529] Lustre: Unmounted lustre-client [ 6391.128617] Lustre: Skipped 1 previous similar message [ 6391.911981] Key type lgssc unregistered [ 6392.014493] LNet: 406916:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6392.017272] LNetError: 406916:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6392.025717] LNet: Removed LNI 192.168.202.14@tcp [ 6392.234094] Key type .llcrypt unregistered [ 6392.235185] Key type ._llcrypt unregistered [ 6392.490819] Key type ._llcrypt registered [ 6392.493950] Key type .llcrypt registered [ 6392.662917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6392.667329] alg: No test for adler32 (adler32-zlib) [ 6393.519116] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6393.597556] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6395.175138] Key type lgssc registered [ 6395.517680] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6399.007841] Lustre: DEBUG MARKER: Iteration 22 [ 6399.138842] LustreError: 407750:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6399.140104] LustreError: 407751:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6399.144098] LustreError: 407750:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6400.218717] Lustre: Mounted lustre-client [ 6400.219777] Lustre: Skipped 1 previous similar message [ 6400.741939] LustreError: 407860:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff382c5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6400.745923] LustreError: 407860:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6400.825129] Lustre: Unmounted lustre-client [ 6400.826227] Lustre: Skipped 1 previous similar message [ 6401.777083] Key type lgssc unregistered [ 6401.897844] LNet: 408103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6401.900640] LNetError: 408103:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6401.909631] LNet: Removed LNI 192.168.202.14@tcp [ 6402.177128] Key type .llcrypt unregistered [ 6402.178131] Key type ._llcrypt unregistered [ 6402.495446] Key type ._llcrypt registered [ 6402.496465] Key type .llcrypt registered [ 6402.734293] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6402.757965] alg: No test for adler32 (adler32-zlib) [ 6403.638944] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6403.731718] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6405.319176] Key type lgssc registered [ 6405.837419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6410.343566] Lustre: DEBUG MARKER: Iteration 23 [ 6410.502965] LustreError: 408934:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6410.504206] LustreError: 408935:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6410.509734] LustreError: 408934:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6411.570713] Lustre: Mounted lustre-client [ 6412.094603] LustreError: 409049:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff387207800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6412.098325] LustreError: 409049:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6412.192139] Lustre: Unmounted lustre-client [ 6413.286923] Key type lgssc unregistered [ 6413.456871] LNet: 409294:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6413.460893] LNetError: 409294:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6413.469123] LNet: Removed LNI 192.168.202.14@tcp [ 6413.829172] Key type .llcrypt unregistered [ 6413.831386] Key type ._llcrypt unregistered [ 6414.122262] Key type ._llcrypt registered [ 6414.123274] Key type .llcrypt registered [ 6414.374275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6414.378793] alg: No test for adler32 (adler32-zlib) [ 6415.261516] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6415.366215] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6416.959101] Key type lgssc registered [ 6417.448975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6421.813437] Lustre: DEBUG MARKER: Iteration 24 [ 6421.952576] LustreError: 410122:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6421.965099] LustreError: 410142:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6421.967153] LustreError: 410122:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 6423.029127] Lustre: Mounted lustre-client [ 6423.591692] LustreError: 410241:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39838d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6423.595431] LustreError: 410241:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6423.688864] Lustre: Unmounted lustre-client [ 6424.599870] Key type lgssc unregistered [ 6424.709448] LNet: 410485:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6424.712347] LNetError: 410485:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6424.720763] LNet: Removed LNI 192.168.202.14@tcp [ 6424.934080] Key type .llcrypt unregistered [ 6424.935194] Key type ._llcrypt unregistered [ 6425.186461] Key type ._llcrypt registered [ 6425.189390] Key type .llcrypt registered [ 6425.365327] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6425.369803] alg: No test for adler32 (adler32-zlib) [ 6426.231838] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6426.319420] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6427.927152] Key type lgssc registered [ 6428.477330] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6432.761980] Lustre: DEBUG MARKER: Iteration 25 [ 6432.854992] LustreError: 411316:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6432.855056] LustreError: 411317:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6432.858928] LustreError: 411316:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6433.910885] Lustre: Mounted lustre-client [ 6434.345410] LustreError: 411428:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff382f59000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6434.349823] LustreError: 411428:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6434.397114] Lustre: Unmounted lustre-client [ 6435.344376] Key type lgssc unregistered [ 6435.495882] LNet: 411670:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6435.498552] LNetError: 411670:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6435.509284] LNet: Removed LNI 192.168.202.14@tcp [ 6435.862174] Key type .llcrypt unregistered [ 6435.864406] Key type ._llcrypt unregistered [ 6436.163437] Key type ._llcrypt registered [ 6436.164431] Key type .llcrypt registered [ 6436.347479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6436.351957] alg: No test for adler32 (adler32-zlib) [ 6437.198320] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6437.273431] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6438.855120] Key type lgssc registered [ 6439.209710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6442.776641] Lustre: DEBUG MARKER: Iteration 26 [ 6442.872450] LustreError: 412503:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6442.872518] LustreError: 412504:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6442.876547] LustreError: 412503:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6443.928423] Lustre: Mounted lustre-client [ 6444.371363] LustreError: 412616:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff388d70800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6444.374706] LustreError: 412616:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6444.434080] Lustre: Unmounted lustre-client [ 6444.435062] Lustre: Skipped 1 previous similar message [ 6445.271359] Key type lgssc unregistered [ 6445.376512] LNet: 412860:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6445.378811] LNetError: 412860:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6445.385647] LNet: Removed LNI 192.168.202.14@tcp [ 6445.606103] Key type .llcrypt unregistered [ 6445.607220] Key type ._llcrypt unregistered [ 6445.872134] Key type ._llcrypt registered [ 6445.880040] Key type .llcrypt registered [ 6446.049197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6446.053871] alg: No test for adler32 (adler32-zlib) [ 6446.900561] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6446.974806] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6448.551121] Key type lgssc registered [ 6448.903287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6452.798758] Lustre: DEBUG MARKER: Iteration 27 [ 6452.902828] LustreError: 413691:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6452.903152] LustreError: 413692:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6452.907126] LustreError: 413691:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6453.963214] Lustre: Mounted lustre-client [ 6453.964446] Lustre: Skipped 1 previous similar message [ 6454.383910] LustreError: 413804:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39c4bf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6454.387477] LustreError: 413804:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6454.452941] Lustre: Unmounted lustre-client [ 6455.545483] Key type lgssc unregistered [ 6455.692849] LNet: 414049:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6455.697599] LNetError: 414049:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6455.714398] LNet: Removed LNI 192.168.202.14@tcp [ 6456.036170] Key type .llcrypt unregistered [ 6456.037725] Key type ._llcrypt unregistered [ 6456.312705] Key type ._llcrypt registered [ 6456.315377] Key type .llcrypt registered [ 6456.495357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6456.501410] alg: No test for adler32 (adler32-zlib) [ 6457.358732] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6457.445967] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6459.039167] Key type lgssc registered [ 6459.487413] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6463.653108] Lustre: DEBUG MARKER: Iteration 28 [ 6463.748139] LustreError: 414880:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6463.748203] LustreError: 414881:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6463.754955] LustreError: 414880:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6464.808353] Lustre: Mounted lustre-client [ 6464.809491] Lustre: Skipped 1 previous similar message [ 6465.238579] LustreError: 414995:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff388ef0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6465.242830] LustreError: 414995:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6465.298059] Lustre: Unmounted lustre-client [ 6466.360087] Key type lgssc unregistered [ 6466.480761] LNet: 415240:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6466.485767] LNetError: 415240:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6466.496039] LNet: Removed LNI 192.168.202.14@tcp [ 6466.831130] Key type .llcrypt unregistered [ 6466.832835] Key type ._llcrypt unregistered [ 6467.123380] Key type ._llcrypt registered [ 6467.127570] Key type .llcrypt registered [ 6467.296949] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6467.302272] alg: No test for adler32 (adler32-zlib) [ 6468.159658] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6468.243181] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6469.823226] Key type lgssc registered [ 6470.306190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6474.230724] Lustre: DEBUG MARKER: Iteration 29 [ 6474.353318] LustreError: 416071:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6474.354771] LustreError: 416072:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6474.358814] LustreError: 416071:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6475.412196] Lustre: Mounted lustre-client [ 6475.857675] LustreError: 416189:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3c0462000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6475.861294] LustreError: 416189:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6475.902236] Lustre: Unmounted lustre-client [ 6476.878990] Key type lgssc unregistered [ 6476.997704] LNet: 416432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6477.001161] LNetError: 416432:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6477.009962] LNet: Removed LNI 192.168.202.14@tcp [ 6477.308143] Key type .llcrypt unregistered [ 6477.309864] Key type ._llcrypt unregistered [ 6477.538288] Key type ._llcrypt registered [ 6477.539495] Key type .llcrypt registered [ 6477.756530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6477.761704] alg: No test for adler32 (adler32-zlib) [ 6478.644400] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6478.732761] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6480.319099] Key type lgssc registered [ 6480.658630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6484.465712] Lustre: DEBUG MARKER: Iteration 30 [ 6484.579607] LustreError: 417263:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6484.579855] LustreError: 417264:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6484.584217] LustreError: 417263:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6485.632842] Lustre: Mounted lustre-client [ 6486.061843] LustreError: 417375:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3999dd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6486.064961] LustreError: 417375:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6486.124694] Lustre: Unmounted lustre-client [ 6487.344716] Key type lgssc unregistered [ 6487.489510] LNet: 417619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6487.494871] LNetError: 417619:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6487.507427] LNet: Removed LNI 192.168.202.14@tcp [ 6487.853135] Key type .llcrypt unregistered [ 6487.854900] Key type ._llcrypt unregistered [ 6488.155967] Key type ._llcrypt registered [ 6488.156887] Key type .llcrypt registered [ 6488.325899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6488.330243] alg: No test for adler32 (adler32-zlib) [ 6489.188684] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6489.269407] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6490.855187] Key type lgssc registered [ 6491.275441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6495.220432] Lustre: DEBUG MARKER: Iteration 31 [ 6495.336750] LustreError: 418451:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6495.339462] LustreError: 418452:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6495.341313] LustreError: 418451:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6496.394976] Lustre: Mounted lustre-client [ 6496.823961] LustreError: 418564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff398932800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6496.828488] LustreError: 418564:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6496.895207] Lustre: Unmounted lustre-client [ 6497.966378] Key type lgssc unregistered [ 6498.091360] LNet: 418808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6498.094375] LNetError: 418808:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6498.103432] LNet: Removed LNI 192.168.202.14@tcp [ 6498.438142] Key type .llcrypt unregistered [ 6498.440486] Key type ._llcrypt unregistered [ 6498.662403] Key type ._llcrypt registered [ 6498.663348] Key type .llcrypt registered [ 6498.853653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6498.858768] alg: No test for adler32 (adler32-zlib) [ 6499.715489] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6499.790623] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6501.367110] Key type lgssc registered [ 6501.860962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6505.545641] Lustre: DEBUG MARKER: Iteration 32 [ 6505.665240] LustreError: 419640:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6505.665293] LustreError: 419641:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6505.669670] LustreError: 419640:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6506.719990] Lustre: Mounted lustre-client [ 6507.133905] LustreError: 419754:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3873b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6507.137939] LustreError: 419754:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6507.191501] Lustre: Unmounted lustre-client [ 6508.005365] Key type lgssc unregistered [ 6508.109528] LNet: 419995:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6508.111652] LNetError: 419995:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6508.120780] LNet: Removed LNI 192.168.202.14@tcp [ 6508.319107] Key type .llcrypt unregistered [ 6508.320879] Key type ._llcrypt unregistered [ 6508.579126] Key type ._llcrypt registered [ 6508.580420] Key type .llcrypt registered [ 6508.756308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6508.760937] alg: No test for adler32 (adler32-zlib) [ 6509.612162] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6509.684519] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6511.263105] Key type lgssc registered [ 6511.600093] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6514.815813] Lustre: DEBUG MARKER: Iteration 33 [ 6514.919029] LustreError: 420827:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6514.919067] LustreError: 420826:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6514.922758] LustreError: 420827:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6515.970983] Lustre: Mounted lustre-client [ 6516.399426] LustreError: 420939:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff387ff8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6516.403737] LustreError: 420939:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6516.466133] Lustre: Unmounted lustre-client [ 6517.454125] Key type lgssc unregistered [ 6517.583615] LNet: 421184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6517.586521] LNetError: 421184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6517.593906] LNet: Removed LNI 192.168.202.14@tcp [ 6517.855085] Key type .llcrypt unregistered [ 6517.856083] Key type ._llcrypt unregistered [ 6518.077056] Key type ._llcrypt registered [ 6518.079290] Key type .llcrypt registered [ 6518.261045] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6518.265302] alg: No test for adler32 (adler32-zlib) [ 6519.118902] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6519.198915] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6520.783106] Key type lgssc registered [ 6521.176425] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6524.382286] Lustre: DEBUG MARKER: Iteration 34 [ 6524.478541] LustreError: 422015:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6524.478798] LustreError: 422016:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6524.482310] LustreError: 422015:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6525.532817] Lustre: Mounted lustre-client [ 6525.960085] LustreError: 422130:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3bff4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6525.964264] LustreError: 422130:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6526.041410] Lustre: Unmounted lustre-client [ 6526.907343] Key type lgssc unregistered [ 6527.017530] LNet: 422374:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6527.019719] LNetError: 422374:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6527.026698] LNet: Removed LNI 192.168.202.14@tcp [ 6527.232100] Key type .llcrypt unregistered [ 6527.233118] Key type ._llcrypt unregistered [ 6527.465836] Key type ._llcrypt registered [ 6527.467018] Key type .llcrypt registered [ 6527.660047] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6527.664674] alg: No test for adler32 (adler32-zlib) [ 6528.507474] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6528.580198] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6530.159166] Key type lgssc registered [ 6530.517877] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6533.728756] Lustre: DEBUG MARKER: Iteration 35 [ 6533.830311] LustreError: 423205:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6533.830369] LustreError: 423206:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6533.835258] LustreError: 423205:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6534.887419] Lustre: Mounted lustre-client [ 6534.888909] Lustre: Skipped 1 previous similar message [ 6535.300836] LustreError: 423320:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39d17c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6535.303792] LustreError: 423320:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6535.346100] Lustre: Unmounted lustre-client [ 6536.466543] Key type lgssc unregistered [ 6536.602584] LNet: 423563:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6536.607400] LNetError: 423563:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6536.617475] LNet: Removed LNI 192.168.202.14@tcp [ 6536.926162] Key type .llcrypt unregistered [ 6536.928441] Key type ._llcrypt unregistered [ 6537.215282] Key type ._llcrypt registered [ 6537.216518] Key type .llcrypt registered [ 6537.389615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6537.394703] alg: No test for adler32 (adler32-zlib) [ 6538.248553] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6538.328312] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6539.911128] Key type lgssc registered [ 6540.403732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6544.090668] Lustre: DEBUG MARKER: Iteration 36 [ 6544.201794] LustreError: 424395:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6544.201829] LustreError: 424396:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6544.207093] LustreError: 424395:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6545.261913] Lustre: Mounted lustre-client [ 6545.263149] Lustre: Skipped 1 previous similar message [ 6545.684568] LustreError: 424506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3873b1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6545.688191] LustreError: 424506:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6545.742510] Lustre: Unmounted lustre-client [ 6546.827434] Key type lgssc unregistered [ 6546.951509] LNet: 424749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6546.956439] LNetError: 424749:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6546.970969] LNet: Removed LNI 192.168.202.14@tcp [ 6547.261128] Key type .llcrypt unregistered [ 6547.262689] Key type ._llcrypt unregistered [ 6547.532476] Key type ._llcrypt registered [ 6547.535650] Key type .llcrypt registered [ 6547.717133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6547.723253] alg: No test for adler32 (adler32-zlib) [ 6548.567130] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6548.642032] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6550.223180] Key type lgssc registered [ 6550.731312] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6554.625968] Lustre: DEBUG MARKER: Iteration 37 [ 6554.736026] LustreError: 425579:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6554.740691] LustreError: 425586:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6554.742829] LustreError: 425579:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6555.815485] Lustre: Mounted lustre-client [ 6555.816617] Lustre: Skipped 1 previous similar message [ 6556.430739] LustreError: 425693:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3873b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6556.434224] LustreError: 425693:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6556.503194] Lustre: Unmounted lustre-client [ 6557.578028] Key type lgssc unregistered [ 6557.702545] LNet: 425937:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6557.706558] LNetError: 425937:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6557.718587] LNet: Removed LNI 192.168.202.14@tcp [ 6557.954103] Key type .llcrypt unregistered [ 6557.955337] Key type ._llcrypt unregistered [ 6558.268191] Key type ._llcrypt registered [ 6558.269346] Key type .llcrypt registered [ 6558.481730] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6558.486819] alg: No test for adler32 (adler32-zlib) [ 6559.352648] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6559.439936] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6561.023185] Key type lgssc registered [ 6561.473771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6565.544231] Lustre: DEBUG MARKER: Iteration 38 [ 6565.637940] LustreError: 426768:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6565.638117] LustreError: 426769:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6565.641716] LustreError: 426768:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6566.693355] Lustre: Mounted lustre-client [ 6567.111967] LustreError: 426882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff397fa0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6567.204198] Lustre: Unmounted lustre-client [ 6567.205127] Lustre: Skipped 1 previous similar message [ 6568.298787] Key type lgssc unregistered [ 6568.427670] LNet: 427127:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6568.430506] LNetError: 427127:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6568.442333] LNet: Removed LNI 192.168.202.14@tcp [ 6568.711190] Key type .llcrypt unregistered [ 6568.713124] Key type ._llcrypt unregistered [ 6569.001728] Key type ._llcrypt registered [ 6569.003983] Key type .llcrypt registered [ 6569.169449] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6569.173990] alg: No test for adler32 (adler32-zlib) [ 6570.030429] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6570.110148] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6571.695129] Key type lgssc registered [ 6572.085638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6575.254039] Lustre: DEBUG MARKER: Iteration 39 [ 6575.349467] LustreError: 427958:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6575.349546] LustreError: 427959:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6575.353330] LustreError: 427958:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6576.409056] Lustre: Mounted lustre-client [ 6576.410277] Lustre: Skipped 1 previous similar message [ 6576.853590] LustreError: 428074:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff39d17f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6576.857908] LustreError: 428074:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6576.960269] Lustre: Unmounted lustre-client [ 6578.016552] Key type lgssc unregistered [ 6578.134665] LNet: 428317:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6578.137912] LNetError: 428317:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6578.145942] LNet: Removed LNI 192.168.202.14@tcp [ 6578.403126] Key type .llcrypt unregistered [ 6578.404931] Key type ._llcrypt unregistered [ 6578.690393] Key type ._llcrypt registered [ 6578.691564] Key type .llcrypt registered [ 6578.862544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6578.867934] alg: No test for adler32 (adler32-zlib) [ 6579.713744] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6579.786535] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6581.367142] Key type lgssc registered [ 6581.841388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6585.372847] Lustre: DEBUG MARKER: Iteration 40 [ 6585.473646] LustreError: 429150:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6585.473678] LustreError: 429151:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6585.477984] LustreError: 429150:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6586.530764] Lustre: Mounted lustre-client [ 6586.531908] Lustre: Skipped 1 previous similar message [ 6586.969451] LustreError: 429266:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38654d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6586.972750] LustreError: 429266:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6587.031724] Lustre: Unmounted lustre-client [ 6587.912587] Key type lgssc unregistered [ 6588.025620] LNet: 429509:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6588.028339] LNetError: 429509:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6588.037743] LNet: Removed LNI 192.168.202.14@tcp [ 6588.248103] Key type .llcrypt unregistered [ 6588.249198] Key type ._llcrypt unregistered [ 6588.509545] Key type ._llcrypt registered [ 6588.510825] Key type .llcrypt registered [ 6588.680187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6588.684808] alg: No test for adler32 (adler32-zlib) [ 6589.543035] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6589.616491] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6591.199130] Key type lgssc registered [ 6591.539970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6594.830173] Lustre: DEBUG MARKER: Iteration 41 [ 6594.936467] LustreError: 430341:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6594.936502] LustreError: 430342:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6594.940680] LustreError: 430341:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6595.990132] Lustre: Mounted lustre-client [ 6596.415958] LustreError: 430454:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b13c5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6596.485089] Lustre: Unmounted lustre-client [ 6597.477966] Key type lgssc unregistered [ 6597.604906] LNet: 430698:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6597.608940] LNetError: 430698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6597.621319] LNet: Removed LNI 192.168.202.14@tcp [ 6597.890151] Key type .llcrypt unregistered [ 6597.891613] Key type ._llcrypt unregistered [ 6598.155866] Key type ._llcrypt registered [ 6598.164852] Key type .llcrypt registered [ 6598.353778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6598.359129] alg: No test for adler32 (adler32-zlib) [ 6599.229888] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6599.302906] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6600.879122] Key type lgssc registered [ 6601.325424] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6604.711867] Lustre: DEBUG MARKER: Iteration 42 [ 6604.807743] LustreError: 431530:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6604.807800] LustreError: 431531:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6604.811561] LustreError: 431530:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6605.861649] Lustre: Mounted lustre-client [ 6605.862597] Lustre: Skipped 1 previous similar message [ 6606.296662] LustreError: 431640:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff399eb4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6606.299568] LustreError: 431640:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6606.414395] Lustre: Unmounted lustre-client [ 6607.259445] Key type lgssc unregistered [ 6607.369503] LNet: 431884:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6607.371601] LNetError: 431884:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6607.379707] LNet: Removed LNI 192.168.202.14@tcp [ 6607.595095] Key type .llcrypt unregistered [ 6607.596148] Key type ._llcrypt unregistered [ 6607.847846] Key type ._llcrypt registered [ 6607.848790] Key type .llcrypt registered [ 6608.018418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6608.023204] alg: No test for adler32 (adler32-zlib) [ 6608.864067] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6608.935330] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6610.511093] Key type lgssc registered [ 6610.833235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6613.895754] Lustre: DEBUG MARKER: Iteration 43 [ 6613.987900] LustreError: 432715:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6613.988060] LustreError: 432716:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6613.992184] LustreError: 432715:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6615.045778] Lustre: Mounted lustre-client [ 6615.472714] LustreError: 432824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff388ef0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6615.475721] LustreError: 432824:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6615.549105] Lustre: Unmounted lustre-client [ 6616.441596] Key type lgssc unregistered [ 6616.552451] LNet: 433068:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6616.556510] LNetError: 433068:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6616.564774] LNet: Removed LNI 192.168.202.14@tcp [ 6616.785120] Key type .llcrypt unregistered [ 6616.786486] Key type ._llcrypt unregistered [ 6617.028527] Key type ._llcrypt registered [ 6617.029571] Key type .llcrypt registered [ 6617.220180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6617.225854] alg: No test for adler32 (adler32-zlib) [ 6618.082226] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6618.157150] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6619.743190] Key type lgssc registered [ 6620.138241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6623.732677] Lustre: DEBUG MARKER: Iteration 44 [ 6623.851298] LustreError: 433899:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6623.851344] LustreError: 433900:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6623.855087] LustreError: 433899:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6624.908861] Lustre: Mounted lustre-client [ 6625.329250] LustreError: 434009:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3c067b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6625.333343] LustreError: 434009:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6625.432700] Lustre: Unmounted lustre-client [ 6626.287604] Key type lgssc unregistered [ 6626.388475] LNet: 434252:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6626.390742] LNetError: 434252:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6626.397856] LNet: Removed LNI 192.168.202.14@tcp [ 6626.613091] Key type .llcrypt unregistered [ 6626.614106] Key type ._llcrypt unregistered [ 6626.869276] Key type ._llcrypt registered [ 6626.870597] Key type .llcrypt registered [ 6627.054763] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6627.059404] alg: No test for adler32 (adler32-zlib) [ 6627.904548] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6627.979098] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6629.551163] Key type lgssc registered [ 6629.903323] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6633.322331] Lustre: DEBUG MARKER: Iteration 45 [ 6633.432885] LustreError: 435084:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6633.432936] LustreError: 435083:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6633.438948] LustreError: 435084:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6634.490415] Lustre: Mounted lustre-client [ 6634.491917] Lustre: Skipped 1 previous similar message [ 6634.921629] LustreError: 435201:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff388cbd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6634.925363] LustreError: 435201:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6634.985082] Lustre: Unmounted lustre-client [ 6635.843430] Key type lgssc unregistered [ 6635.967519] LNet: 435444:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6635.970300] LNetError: 435444:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6635.978685] LNet: Removed LNI 192.168.202.14@tcp [ 6636.212107] Key type .llcrypt unregistered [ 6636.213666] Key type ._llcrypt unregistered [ 6636.556953] Key type ._llcrypt registered [ 6636.564345] Key type .llcrypt registered [ 6636.679804] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6636.685704] alg: No test for adler32 (adler32-zlib) [ 6637.553863] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6637.637491] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6639.223121] Key type lgssc registered [ 6639.572471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6642.837291] Lustre: DEBUG MARKER: Iteration 46 [ 6642.940423] LustreError: 436272:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6642.947636] LustreError: 436280:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6642.949485] LustreError: 436272:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 6644.012924] Lustre: Mounted lustre-client [ 6644.538372] LustreError: 436390:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff382f5b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6644.542650] LustreError: 436390:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6644.649062] Lustre: Unmounted lustre-client [ 6645.616983] Key type lgssc unregistered [ 6645.736746] LNet: 436634:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6645.739607] LNetError: 436634:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6645.750122] LNet: Removed LNI 192.168.202.14@tcp [ 6645.988106] Key type .llcrypt unregistered [ 6645.989280] Key type ._llcrypt unregistered [ 6646.338297] Key type ._llcrypt registered [ 6646.340285] Key type .llcrypt registered [ 6646.541842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6646.546565] alg: No test for adler32 (adler32-zlib) [ 6647.409818] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6647.496076] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6649.079110] Key type lgssc registered [ 6649.459151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6652.743708] Lustre: DEBUG MARKER: Iteration 47 [ 6652.851490] LustreError: 437467:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6652.851524] LustreError: 437466:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6652.856705] LustreError: 437467:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6653.910298] Lustre: Mounted lustre-client [ 6654.335829] LustreError: 437577:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38d322800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6654.339014] LustreError: 437577:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6654.412090] Lustre: Unmounted lustre-client [ 6655.235587] Key type lgssc unregistered [ 6655.339489] LNet: 437821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6655.341669] LNetError: 437821:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6655.349719] LNet: Removed LNI 192.168.202.14@tcp [ 6655.548077] Key type .llcrypt unregistered [ 6655.549219] Key type ._llcrypt unregistered [ 6655.830250] Key type ._llcrypt registered [ 6655.833508] Key type .llcrypt registered [ 6655.998951] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6656.003608] alg: No test for adler32 (adler32-zlib) [ 6656.845510] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6656.920550] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6658.495141] Key type lgssc registered [ 6658.820273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6662.376350] Lustre: DEBUG MARKER: Iteration 48 [ 6662.507435] LustreError: 438652:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6662.507481] LustreError: 438653:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6662.511708] LustreError: 438652:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6663.564571] Lustre: Mounted lustre-client [ 6663.994312] LustreError: 438762:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff38dd42800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6663.998610] LustreError: 438762:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6664.059203] Lustre: Unmounted lustre-client [ 6665.139394] Key type lgssc unregistered [ 6665.277816] LNet: 439007:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6665.282647] LNetError: 439007:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6665.293547] LNet: Removed LNI 192.168.202.14@tcp [ 6665.684198] Key type .llcrypt unregistered [ 6665.686427] Key type ._llcrypt unregistered [ 6666.080827] Key type ._llcrypt registered [ 6666.082047] Key type .llcrypt registered [ 6666.170329] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6666.175371] alg: No test for adler32 (adler32-zlib) [ 6667.053817] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6667.154121] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6668.767173] Key type lgssc registered [ 6669.409961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6673.306321] Lustre: DEBUG MARKER: Iteration 49 [ 6673.433851] LustreError: 439839:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6673.434260] LustreError: 439840:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6673.437901] LustreError: 439839:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6674.488071] Lustre: Mounted lustre-client [ 6674.489573] Lustre: Skipped 1 previous similar message [ 6674.902939] LustreError: 439955:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3994b7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6674.906788] LustreError: 439955:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6674.978176] Lustre: Unmounted lustre-client [ 6676.262324] Key type lgssc unregistered [ 6676.417937] LNet: 440198:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6676.422686] LNetError: 440198:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6676.435327] LNet: Removed LNI 192.168.202.14@tcp [ 6676.802203] Key type .llcrypt unregistered [ 6676.804218] Key type ._llcrypt unregistered [ 6677.183588] Key type ._llcrypt registered [ 6677.184895] Key type .llcrypt registered [ 6677.274972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6677.279669] alg: No test for adler32 (adler32-zlib) [ 6678.139794] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6678.214709] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6679.799270] Key type lgssc registered [ 6680.388974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6685.162256] Lustre: DEBUG MARKER: Iteration 50 [ 6685.280384] LustreError: 441029:0:(llite_lib.c:1370:ll_fill_super()) cfs_race id 1417 sleeping [ 6685.280435] LustreError: 441030:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 waking [ 6685.284894] LustreError: 441029:0:(llite_lib.c:1370:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6686.332705] Lustre: Mounted lustre-client [ 6686.781977] LustreError: 441143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff387206000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6686.787397] LustreError: 441143:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6686.833668] Lustre: Unmounted lustre-client [ 6687.966858] Key type lgssc unregistered [ 6688.116632] LNet: 441385:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6688.120128] LNetError: 441385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6688.127574] LNet: Removed LNI 192.168.202.14@tcp [ 6688.462177] Key type .llcrypt unregistered [ 6688.464345] Key type ._llcrypt unregistered [ 6688.760251] Key type ._llcrypt registered [ 6688.761559] Key type .llcrypt registered [ 6688.935051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6688.940436] alg: No test for adler32 (adler32-zlib) [ 6689.810118] Lustre: Lustre: Build Version: 2.17.50_236_gea5aa0c [ 6689.908874] LNet: Added LNI 192.168.202.14@tcp [8/256/0/180] [ 6691.495185] Key type lgssc registered [ 6692.105206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6696.900128] Lustre: Mounted lustre-client [ 6699.261068] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:08:51 (1773108531) [ 6715.871142] Lustre: 442740:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108532/real 1773108532] req@ffff8ff3b11f5f80 x1859239041575296/t0(0) o36->lustre-MDT0000-mdc-ffff8ff3b49c9800@192.168.202.114@tcp:12/10 lens 496/440 e 0 to 1 dl 1773108548 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6715.877778] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection to lustre-MDT0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6715.886928] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6731.231125] Lustre: 442740:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108548/real 1773108548] req@ffff8ff3b11f5f80 x1859239041575296/t0(0) o36->lustre-MDT0000-mdc-ffff8ff3b49c9800@192.168.202.114@tcp:12/10 lens 496/440 e 0 to 1 dl 1773108564 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6731.242615] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection to lustre-MDT0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6731.254031] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6747.615139] Lustre: 442740:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108564/real 1773108564] req@ffff8ff3b11f5f80 x1859239041575296/t0(0) o36->lustre-MDT0000-mdc-ffff8ff3b49c9800@192.168.202.114@tcp:12/10 lens 496/440 e 0 to 1 dl 1773108580 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6747.623190] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection to lustre-MDT0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6747.632438] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6763.999117] Lustre: 442740:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108580/real 1773108580] req@ffff8ff3b11f5f80 x1859239041575296/t0(0) o36->lustre-MDT0000-mdc-ffff8ff3b49c9800@192.168.202.114@tcp:12/10 lens 496/440 e 0 to 1 dl 1773108596 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6764.005402] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection to lustre-MDT0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6764.015860] Lustre: lustre-MDT0000-mdc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6764.464588] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:09:57 (1773108597) [ 6769.775889] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:10:02 (1773108602) [ 6772.734627] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:10:05 (1773108605) [ 6774.808486] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:10:07 (1773108607) [ 6781.854235] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:10:14 (1773108614) [ 6793.955786] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:10:26 (1773108626) [ 6796.221986] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 22:10:28 (1773108628) [ 6796.294948] LustreError: 447514:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6798.375089] LustreError: 447514:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6800.446756] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:10:32 (1773108632) [ 6818.271168] Lustre: 441580:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108635/real 1773108635] req@ffff8ff397e9aa00 x1859239042611584/t0(0) o4->lustre-OST0000-osc-ffff8ff3b49c9800@192.168.202.114@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773108651 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6818.271213] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection to lustre-OST0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6818.283196] Lustre: 441580:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6818.297654] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6834.143078] Lustre: 441580:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108651/real 1773108651] req@ffff8ff397e9aa00 x1859239042611584/t0(0) o4->lustre-OST0000-osc-ffff8ff3b49c9800@192.168.202.114@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773108667 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6834.149933] Lustre: 441580:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 6834.151872] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection to lustre-OST0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6834.160162] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6850.527081] Lustre: 441579:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773108667/real 1773108667] req@ffff8ff3952c7b80 x1859239042612352/t0(0) o4->lustre-OST0000-osc-ffff8ff3b49c9800@192.168.202.114@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773108683 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6850.527145] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection to lustre-OST0000 (at 192.168.202.114@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6850.533621] Lustre: 441579:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6850.542045] Lustre: lustre-OST0000-osc-ffff8ff3b49c9800: Connection restored to 192.168.202.114@tcp (at 192.168.202.114@tcp) [ 6891.069621] Lustre: DEBUG MARKER: oleg214-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ff398933000.ost_server_uuid 50 [ 6891.673568] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ff398933000.ost_server_uuid in IDLE state after 0 sec [ 6892.505555] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6893.348823] Lustre: DEBUG MARKER: == sanityn test complete, duration 6722 sec ============== 22:12:05 (1773108725) [ 6894.051789] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:12:06 (1773108726) === [ 6966.577813] LustreError: 449564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff398933000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6966.601132] Lustre: Unmounted lustre-client [ 6968.133162] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:13:20 (1773108800) === [ 6968.448726] LustreError: 449867:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8ff3b49c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6968.451825] LustreError: 449867:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6968.485543] Lustre: Unmounted lustre-client [ 7003.905721] Key type lgssc unregistered [ 7004.016659] LNet: 450551:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7004.021979] LNetError: 450551:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7004.034456] LNet: Removed LNI 192.168.202.14@tcp [ 7004.330113] Key type .llcrypt unregistered [ 7004.331185] Key type ._llcrypt unregistered