[ 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 428192502 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002276] x2apic enabled [ 0.003000] Switched APIC routing to physical x2apic. [ 0.003012] kvm-guest: setup PV IPIs [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007016] pid_max: default: 32768 minimum: 301 [ 0.008115] LSM: Security Framework initializing [ 0.009042] Yama: becoming mindful. [ 0.010027] SELinux: Initializing. [ 0.011000] *** VALIDATE selinux *** [ 0.018360] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023208] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024142] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026034] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027107] *** VALIDATE tmpfs *** [ 0.028496] *** VALIDATE proc *** [ 0.029244] *** VALIDATE cgroup *** [ 0.030006] *** VALIDATE cgroup2 *** [ 0.031263] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032151] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034026] Spectre V2 : User space: Vulnerable [ 0.035008] Speculative Store Bypass: Vulnerable [ 0.038351] debug: unmapping init [mem 0xffffffff93e59000-0xffffffff93e60fff] [ 0.040845] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041775] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042029] ... version: 2 [ 0.043016] ... bit width: 48 [ 0.044010] ... generic registers: 4 [ 0.045009] ... value mask: 0000ffffffffffff [ 0.046008] ... max period: 00007fffffffffff [ 0.047007] ... fixed-purpose events: 3 [ 0.048011] ... event mask: 000000070000000f [ 0.050273] rcu: Hierarchical SRCU implementation. [ 0.052499] smp: Bringing up secondary CPUs ... [ 0.053548] x86: Booting SMP configuration: [ 0.054037] .... node #0, CPUs: #1 #2 #3 [ 0.058190] smp: Brought up 1 node, 4 CPUs [ 0.060017] smpboot: Max logical packages: 1 [ 0.061012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.138016] node 0 deferred pages initialised in 76ms [ 0.141264] devtmpfs: initialized [ 0.142202] x86/mm: Memory block size: 128MB [ 0.144000] gcov: version magic: 0x41383552 [ 0.147156] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149057] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.151285] pinctrl core: initialized pinctrl subsystem [ 0.153127] [ 0.153474] ************************************************************* [ 0.155008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.156005] ** ** [ 0.158007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.159006] ** ** [ 0.161007] ** This means that this kernel is built to expose internal ** [ 0.162005] ** IOMMU data structures, which may compromise security on ** [ 0.164006] ** your system. ** [ 0.165007] ** ** [ 0.166006] ** If you see this message and you are not debugging the ** [ 0.168006] ** kernel, report this immediately to your vendor! ** [ 0.169006] ** ** [ 0.171007] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.173006] ************************************************************* [ 0.174587] NET: Registered protocol family 16 [ 0.176405] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.178047] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.179050] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.182111] cpuidle: using governor menu [ 0.183684] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.186447] PCI: Using configuration type 1 for base access [ 0.188142] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.197148] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.200043] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.202204] cryptd: max_cpu_qlen set to 1000 [ 0.205270] ACPI: Added _OSI(Module Device) [ 0.207014] ACPI: Added _OSI(Processor Device) [ 0.208010] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.209016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.213717] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.218422] ACPI: Interpreter enabled [ 0.220035] ACPI: PM: (supports S0 S3 S4 S5) [ 0.221009] ACPI: Using IOAPIC for interrupt routing [ 0.222099] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.225374] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.235834] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.237043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.240015] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.242068] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.247284] acpiphp: Slot [2] registered [ 0.248120] acpiphp: Slot [5] registered [ 0.249134] acpiphp: Slot [6] registered [ 0.251094] acpiphp: Slot [3] registered [ 0.252106] acpiphp: Slot [4] registered [ 0.253092] acpiphp: Slot [7] registered [ 0.255087] acpiphp: Slot [8] registered [ 0.257105] acpiphp: Slot [9] registered [ 0.258074] acpiphp: Slot [10] registered [ 0.259091] acpiphp: Slot [11] registered [ 0.261099] acpiphp: Slot [12] registered [ 0.262067] acpiphp: Slot [13] registered [ 0.263086] acpiphp: Slot [14] registered [ 0.265072] acpiphp: Slot [15] registered [ 0.267068] acpiphp: Slot [16] registered [ 0.268063] acpiphp: Slot [17] registered [ 0.269082] acpiphp: Slot [18] registered [ 0.271110] acpiphp: Slot [19] registered [ 0.272073] acpiphp: Slot [20] registered [ 0.273077] acpiphp: Slot [21] registered [ 0.274071] acpiphp: Slot [22] registered [ 0.276078] acpiphp: Slot [23] registered [ 0.277081] acpiphp: Slot [24] registered [ 0.279087] acpiphp: Slot [25] registered [ 0.280034] acpiphp: Slot [26] registered [ 0.281068] acpiphp: Slot [27] registered [ 0.282047] acpiphp: Slot [28] registered [ 0.284070] acpiphp: Slot [29] registered [ 0.285073] acpiphp: Slot [30] registered [ 0.286150] acpiphp: Slot [31] registered [ 0.287043] PCI host bridge to bus 0000:00 [ 0.288011] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.290018] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.292018] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.295028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.297030] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.301151] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.302251] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.305987] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.308133] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.314013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.317044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.319011] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.320011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.322012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.324491] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.327793] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.329027] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.331697] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.334011] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.342015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.345939] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.352066] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.359020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.364020] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.378917] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.389821] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.395205] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.401010] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.414850] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.423709] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.426253] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.428352] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.430340] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.433229] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.437112] iommu: Default domain type: Passthrough [ 0.439532] SCSI subsystem initialized [ 0.441097] ACPI: bus type USB registered [ 0.442073] usbcore: registered new interface driver usbfs [ 0.444037] usbcore: registered new interface driver hub [ 0.445066] usbcore: registered new device driver usb [ 0.447173] pps_core: LinuxPPS API ver. 1 registered [ 0.449007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.451124] PTP clock support registered [ 0.455262] EDAC MC: Ver: 3.0.0 [ 0.458223] PCI: Using ACPI for IRQ routing [ 0.461050] NetLabel: Initializing [ 0.462015] NetLabel: domain hash size = 128 [ 0.463012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.465101] NetLabel: unlabeled traffic allowed by default [ 0.468152] vgaarb: loaded [ 0.470532] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.471017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.478000] clocksource: Switched to clocksource kvm-clock [ 0.579853] VFS: Disk quotas dquot_6.6.0 [ 0.581884] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.584436] *** VALIDATE ramfs *** [ 0.585620] *** VALIDATE hugetlbfs *** [ 0.587566] pnp: PnP ACPI init [ 0.590065] pnp: PnP ACPI: found 6 devices [ 0.609557] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.612947] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.615163] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.617768] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.620265] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.622743] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.625321] NET: Registered protocol family 2 [ 0.627429] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.632082] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.636316] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.641439] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.647656] TCP: Hash tables configured (established 65536 bind 65536) [ 0.650527] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.655234] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.658164] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.661046] NET: Registered protocol family 1 [ 0.663527] RPC: Registered named UNIX socket transport module. [ 0.665285] RPC: Registered udp transport module. [ 0.667146] RPC: Registered tcp transport module. [ 0.668720] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.670961] NET: Registered protocol family 44 [ 0.672705] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.674917] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.677289] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.679677] PCI: CLS 0 bytes, default 64 [ 0.682796] Unpacking initramfs... [ 2.108656] debug: unmapping init [mem 0xffff9d523cc64000-0xffff9d523ffcffff] [ 2.112969] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.115430] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.118224] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.652877] Initialise system trusted keyrings [ 2.654644] Key type blacklist registered [ 2.657778] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.667496] zbud: loaded [ 2.673229] *** VALIDATE nfs *** [ 2.674989] *** VALIDATE nfs4 *** [ 2.681579] pstore: using deflate compression [ 2.687701] Platform Keyring initialized [ 2.837861] NET: Registered protocol family 38 [ 2.839668] Key type asymmetric registered [ 2.841996] Asymmetric key parser 'x509' registered [ 2.846775] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.852216] io scheduler mq-deadline registered [ 2.854655] io scheduler kyber registered [ 2.857691] io scheduler bfq registered [ 2.861909] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.866643] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.870207] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.874151] ACPI: Power Button [PWRF] [ 2.882639] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.895275] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.909332] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.941467] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.974306] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.982856] Non-volatile memory driver v1.3 [ 2.984232] Linux agpgart interface v0.103 [ 3.054231] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.059089] vda: detected capacity change from 0 to 68579328 [ 3.085839] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.088588] vdb: detected capacity change from 0 to 1073741824 [ 3.095323] libphy: Fixed MDIO Bus: probed [ 3.100425] usbcore: registered new interface driver usbserial_generic [ 3.102825] usbserial: USB Serial support registered for generic [ 3.104989] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.109334] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.110984] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.113700] mousedev: PS/2 mouse device common for all mice [ 3.121906] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.128162] rtc_cmos 00:05: RTC can wake from S4 [ 3.131767] rtc_cmos 00:05: registered as rtc0 [ 3.133604] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.136160] intel_pstate: CPU model not supported [ 3.140178] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.140810] hid: raw HID events driver (C) Jiri Kosina [ 3.149127] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.150931] usbcore: registered new interface driver usbhid [ 3.150937] usbhid: USB HID core driver [ 3.151847] drop_monitor: Initializing network drop monitor service [ 3.167412] Initializing XFRM netlink socket [ 3.170410] NET: Registered protocol family 10 [ 3.174857] Segment Routing with IPv6 [ 3.176042] NET: Registered protocol family 17 [ 3.216981] mpls_gso: MPLS GSO support [ 3.229161] RAS: Correctable Errors collector initialized. [ 3.236372] AVX version of gcm_enc/dec engaged. [ 3.243590] AES CTR mode by8 optimization enabled [ 3.389898] sched_clock: Marking stable (3389879872, 0)->(4163455335, -773575463) [ 3.394609] registered taskstats version 1 [ 3.397639] Loading compiled-in X.509 certificates [ 3.403875] zswap: loaded using pool lzo/zbud [ 3.490513] Key type big_key registered [ 3.520109] Key type encrypted registered [ 3.521858] ima: No TPM chip found, activating TPM-bypass! [ 3.526688] ima: Allocated hash algorithm: sha1 [ 3.529604] ima: No architecture policies found [ 3.532636] evm: Initialising EVM extended attributes: [ 3.535775] evm: security.selinux [ 3.537493] evm: security.ima [ 3.539810] evm: security.capability [ 3.541589] evm: HMAC attrs: 0x1 [ 3.545903] rtc_cmos 00:05: setting system clock to 2026-04-23 04:39:10 UTC (1776919150) [ 3.558570] debug: unmapping init [mem 0xffffffff94e03000-0xffffffff94ffffff] [ 3.569804] debug: unmapping init [mem 0xffffffff93b82000-0xffffffff93e58fff] [ 3.585858] Write protecting the kernel read-only data: 28672k [ 3.591545] debug: unmapping init [mem 0xffffffff92203000-0xffffffff923fffff] [ 3.602883] debug: unmapping init [mem 0xffffffff92b14000-0xffffffff92bfffff] [ 3.645154] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.665563] systemd[1]: Detected virtualization kvm. [ 3.674843] systemd[1]: Detected architecture x86-64. [ 3.677431] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.709655] systemd[1]: No hostname configured. [ 3.714500] systemd[1]: Set hostname to . [ 3.717680] random: systemd: uninitialized urandom read (16 bytes read) [ 3.729117] systemd[1]: Initializing machine ID from random generator. [ 3.845029] random: ln: uninitialized urandom read (6 bytes read) [ 4.068422] random: systemd: uninitialized urandom read (16 bytes read) [ 4.071310] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.080972] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.093490] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Sockets. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.764704] device-mapper: uevent: version 1.0.3 [ 5.768185] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 9.144498] virtio_net virtio0 ens2: renamed from eth0 [ 9.310717] random: fast init done [ 10.679901] scsi host0: ata_piix [ 11.130404] scsi host1: ata_piix [ 11.135863] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 11.156322] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 16.244451] random: crng init done [ 16.248632] random: 7 urandom warning(s) missed due to ratelimiting [ 19.684249] dracut-initqueue[593]: RTNETLINK answers: File exists Starting nbd nbd0... [ 20.935035] hrtimer: interrupt took 3147107 ns [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 21.442426] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ 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 Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped 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... [ 24.163778] printk: systemd: 19 output lines suppressed due to ratelimiting [ 24.939437] SELinux: Disabled at runtime. [ 25.049597] 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) [ 25.061713] systemd[1]: Detected virtualization kvm. [ 25.064178] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.809844] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.815914] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 26.830746] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 26.841599] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 26.846307] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.865193] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.875990] systemd[1]: Stopped target Switch Root. [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Initrd Root File System. Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Initrd File Systems. Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target R[ 27.316464] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS PC Port Mapper. Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 28.040076] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 28.700946] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 28.834210] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.129530] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.253112] EDAC sbridge: Ver: 1.1.2 [ 33.146361] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (6s / no limit)[ 33.979704] NFS: Registering the id_resolver key type [ 33.981819] Key type id_resolver registered [ 33.983555] Key type id_legacy registered [** ] A start job is running for Configur…-only root support (7s / no limit) [ 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 dnf makecache --timer. [ 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. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. Starting Login Service... Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ 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 Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg304-client login: [ 86.840705] libcfs: loading out-of-tree module taints kernel. [ 86.925126] Key type ._llcrypt registered [ 86.930173] Key type .llcrypt registered [ 87.276741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 87.293853] alg: No test for adler32 (adler32-zlib) [ 88.414358] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 88.892122] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 90.559361] Key type lgssc registered [ 91.287362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 181.298379] Lustre: Mounted lustre-client [ 184.972891] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 197.192968] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing check_logdir /tmp/testlogs/ [ 199.236781] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing yml_node [ 201.590949] Lustre: DEBUG MARKER: Client: 2.17.51.74 [ 202.965201] Lustre: DEBUG MARKER: MDS: 2.17.51.74 [ 204.459239] Lustre: DEBUG MARKER: OSS: 2.17.51.74 [ 205.433309] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Apr 23 00:42:31 EDT 2026 [ 206.815157] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: disconnect after 23s idle [ 215.797122] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 216.795389] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 217.693705] Lustre: DEBUG MARKER: === sanityn: start setup 00:42:43 (1776919363) === [ 218.126323] Lustre: Mounted lustre-client [ 220.311643] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing check_config_client /mnt/lustre [ 231.984513] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 238.315458] Lustre: DEBUG MARKER: === sanityn: finish setup 00:43:04 (1776919384) === [ 238.559660] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 20s idle [ 238.566939] Lustre: Skipped 1 previous similar message [ 239.595194] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 00:43:05 (1776919385) [ 244.478470] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 00:43:10 (1776919390) [ 248.590674] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 00:43:14 (1776919394) [ 252.717582] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 00:43:18 (1776919398) [ 256.715893] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 00:43:22 (1776919402) [ 260.509990] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 00:43:26 (1776919406) [ 264.342187] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 00:43:30 (1776919410) [ 268.357936] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 00:43:34 (1776919414) [ 273.175029] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 00:43:39 (1776919419) [ 277.625464] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 00:43:43 (1776919423) [ 281.752445] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 00:43:47 (1776919427) [ 286.828507] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 00:43:52 (1776919432) [ 291.119738] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 00:43:57 (1776919437) [ 295.588624] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 00:44:01 (1776919441) [ 300.071973] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 00:44:06 (1776919446) [ 304.164818] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 00:44:10 (1776919450) [ 308.761751] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 00:44:14 (1776919454) [ 312.835646] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 00:44:19 (1776919459) [ 317.458657] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 00:44:23 (1776919463) [ 321.493738] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 00:44:27 (1776919467) [ 325.955697] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 00:44:32 (1776919472) [ 326.341542] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 478.214585] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 00:47:04 (1776919624) [ 482.680548] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 00:47:08 (1776919628) [ 486.209091] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 00:47:12 (1776919632) [ 489.754850] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 00:47:15 (1776919635) [ 493.572879] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 00:47:19 (1776919639) [ 496.909331] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 00:47:23 (1776919643) [ 497.800474] Lustre: DEBUG MARKER: chmod [ 501.350983] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 00:47:27 (1776919647) [ 1027.039348] Lustre: lustre-OST0001-osc-ffff9d5298c12800: disconnect after 20s idle [ 1027.041741] Lustre: Skipped 1 previous similar message [ 1030.077588] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 00:56:15 (1776920175) [ 1032.159267] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 25s idle [ 1165.283948] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 24s idle [ 1221.481809] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 00:59:27 (1776920367) [ 1272.643775] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 01:00:18 (1776920418) [ 1349.810086] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 01:01:36 (1776920496) [ 1366.561895] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 01:01:52 (1776920512) [ 1370.150566] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 01:01:56 (1776920516) [ 1370.640922] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.677324] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.715673] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.756366] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.799715] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.836017] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.878161] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.939977] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1370.984173] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.022844] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.063259] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.103074] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.136342] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.169934] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.213601] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.248933] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.286817] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.318823] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.348127] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.379516] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.424379] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.469509] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.527419] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.561863] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.597865] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.645881] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.703133] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.745627] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.803416] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.872877] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.927487] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1371.987469] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.055861] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.109682] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.167231] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.211713] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.254824] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.309536] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.357580] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.397467] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.445722] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.494522] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.534646] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.590564] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.639581] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.675670] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.711774] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.746339] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.781925] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.825882] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.865181] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.899164] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.932913] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1372.968389] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.001593] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.033547] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.061901] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.108473] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.167580] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.218603] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.275130] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.327049] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.362076] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.397934] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.439247] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.472319] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.504739] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.537733] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.572795] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.609497] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.656733] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.690946] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.735140] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.777904] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.805974] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.839852] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.881153] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.917982] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.953807] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1373.983559] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.035735] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.065462] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.098432] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.133334] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.183177] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.228290] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.275295] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.314809] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.354864] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.390396] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.423778] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.455084] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.496268] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.536152] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.565501] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.605603] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.642365] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.676664] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.711929] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.761750] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.791287] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.824398] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.866534] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.907797] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.944433] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1374.976482] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.008151] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.046249] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.078378] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.116293] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.150058] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.179051] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.208786] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.247751] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.281515] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.316686] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.349551] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.382431] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.436083] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.478073] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.525755] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.555313] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.590581] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.625270] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.662711] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.697071] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.738824] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.779473] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.823646] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.866803] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.906295] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.937939] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1375.997774] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.033282] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.080568] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.119746] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.149333] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.201489] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.245711] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.279316] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.314348] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.364835] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.403701] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.446213] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.485293] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.524814] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.565132] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.598731] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.624398] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.663233] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.699686] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.728606] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.770140] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.804815] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.843362] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.876072] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.906134] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.937883] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1376.971842] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.012644] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.053559] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.084324] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.114856] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.145760] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.180613] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.218886] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.252047] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.287729] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.318918] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.349667] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.383033] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.413392] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.446837] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.479975] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.518902] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.548140] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.580771] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.624881] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.656702] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.707853] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.754231] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.810699] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.852557] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.893065] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.937940] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1377.975910] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.008703] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.068248] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.100215] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.151690] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.201633] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.268840] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.345787] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.393970] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.431137] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.460100] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.495230] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.534458] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.576110] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.617808] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.655586] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.688061] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.722866] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.768179] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.809521] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.858428] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.899760] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.950935] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1378.988062] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.022411] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.052686] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.091463] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.134775] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.180713] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.208590] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.237029] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.264578] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.293875] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.324243] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.363215] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.402352] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.439556] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.469705] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.498582] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.535475] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.576510] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.614887] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.645292] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.677313] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.711665] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.740963] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.769955] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.801624] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.841287] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.879062] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.920984] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1379.974463] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.017159] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.047764] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.095482] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.147416] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.179858] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.224293] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.269114] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.301263] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.332614] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.373847] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.406555] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.439716] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.467462] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.500974] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.543692] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.572201] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.598309] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.630939] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.660322] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.694148] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.728685] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.755825] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.785443] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.815446] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.842972] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.867977] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.895641] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.934024] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1380.973472] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.002148] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.034544] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.065591] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.110038] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.139202] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.170714] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.202281] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.233603] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.273803] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.310410] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.345110] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.387281] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.427545] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.460839] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.504156] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.549350] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.591966] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.620157] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.648549] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.682896] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.711216] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.745674] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.779415] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.820530] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.851420] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.887754] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.932282] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1381.966327] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.011573] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.054221] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.098031] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.138887] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.163187] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.191997] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.241444] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.285667] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.332736] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.369209] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.402535] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.440338] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.473426] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.527741] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.570201] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.602627] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.637336] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.670251] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.701539] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.738150] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.775107] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.820372] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.865054] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.898834] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.925750] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1382.961615] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.014833] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.068931] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.100790] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.148605] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.187864] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.229420] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.261548] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.298372] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.334372] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.374657] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.411813] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.452487] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.498536] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.539908] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.589125] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.624049] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.665125] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.711348] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.757856] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.803199] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.858269] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.912212] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.949612] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1383.995525] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.035936] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.081280] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.126308] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.177429] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.212930] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.253527] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.299817] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.334504] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.371806] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.417365] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.470565] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.503335] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.539503] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.580497] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.617225] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.656733] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.709839] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.750233] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.785689] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.820673] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.853410] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.900691] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.951257] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1384.996839] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.054967] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.100835] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.150298] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.196115] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.252223] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.302240] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.346092] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.394740] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.437602] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.477168] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.517894] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.563493] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.618324] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.659873] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.703095] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.764527] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.809150] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.859390] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.915593] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.955865] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1385.997829] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.051520] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.102446] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.144216] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.189477] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.224403] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.257215] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.297516] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.332091] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.368909] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.408317] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.447167] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.481509] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.537623] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.569975] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.611939] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.650112] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.687509] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.732399] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.766102] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.809827] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.845929] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.880726] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.917487] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.944561] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.970711] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1386.999493] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.038996] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.073593] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.101935] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.135198] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.163071] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.195046] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.228288] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.265769] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.324516] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.380662] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.429076] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.463720] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.500188] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.552453] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.586299] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.618638] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.651223] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.682696] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.709495] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.738485] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.764116] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.793678] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.826155] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.853076] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.879445] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.919633] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.961674] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1387.997826] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.046137] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.089337] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.120865] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.167369] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.198813] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.260653] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.303976] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.358390] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.397707] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.464825] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.500262] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.528988] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.562692] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.591217] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.633427] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.689239] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.717756] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.747895] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.774447] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.817474] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.845632] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.877333] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.906121] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.943122] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1388.977686] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.009514] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.035676] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.062530] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.095416] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.126371] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.169063] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.200274] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.239115] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.272523] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.300273] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.330466] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.359639] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.393369] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.416793] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.446755] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.476491] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.508141] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.540126] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.579880] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.612543] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.643118] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.669919] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.698520] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.726889] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.760820] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.791617] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.821668] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.846890] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.879033] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.907509] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.936573] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1389.976891] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.012245] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.039414] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.067214] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.098306] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.132255] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.165795] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.200595] rw_seq_cst_vs_d (32435): drop_caches: 3 [ 1390.559503] Lustre: lustre-OST0001-osc-ffff9d5298c12800: disconnect after 23s idle [ 1393.602148] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 01:02:19 (1776920539) [ 1393.745066] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1393.773429] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1393.837410] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1393.947397] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1393.968418] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1393.999256] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.056158] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.076024] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.099550] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.125830] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.173224] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.222437] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.351191] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.368077] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.643242] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.728576] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.758169] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.843411] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1394.861929] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1395.730595] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1395.760765] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1395.783896] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1395.807866] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1395.827153] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.012849] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.029066] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.246052] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.296821] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.316451] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.343886] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.369852] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.389426] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.408122] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.429377] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.449514] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.478537] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.595122] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.814574] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.883432] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1396.959236] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.050264] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.349954] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.372942] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.399371] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.428095] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.602678] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.765878] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.810083] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.836644] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.856403] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1397.878487] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.006187] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.142919] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.217047] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.386630] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.422485] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.507844] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.553646] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.612353] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.691383] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.769546] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.790229] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.861663] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1398.889568] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.007336] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.040464] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.067389] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.214673] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.267413] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.378686] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.428105] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.808462] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.861874] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.903060] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.949409] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.970892] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1399.996830] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.015903] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.089327] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.111541] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.139446] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.171223] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.191241] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.326622] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.349531] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.378628] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.547879] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.634275] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.708696] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.810223] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.846123] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.883413] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1400.917656] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.052280] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.084570] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.170474] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.191462] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.397697] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.459314] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.499346] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.526123] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.545676] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.569367] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.594373] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.626294] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.657977] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.691538] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.769750] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1401.827615] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.000041] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.116457] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.206845] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.299146] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.527710] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.551675] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.603529] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.674095] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.690700] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.726344] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.869447] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1402.997464] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.105614] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.127756] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.149662] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.254790] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.280994] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.326944] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.389873] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.630730] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.651480] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1403.690542] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.088974] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.115981] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.131605] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.228952] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.255216] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.363369] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.389490] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.421472] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.441957] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.463299] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.491188] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.510340] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.614109] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.693848] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.741547] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.764358] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.813523] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.952451] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1404.980681] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.238331] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.286022] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.347857] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.391751] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.418203] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.666451] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.686063] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.829262] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1405.861609] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.013393] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.041115] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.068560] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.087228] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.120790] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.154040] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.182969] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.214165] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.234498] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.254095] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.281059] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.320078] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.338534] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.367431] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.389944] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.462820] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.489821] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.507302] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.533281] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.551936] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.570216] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.645492] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.663612] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.682891] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.704508] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.730169] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.748400] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.767402] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.785298] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.805956] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.833462] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.861027] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.879548] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.909744] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.927570] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1406.946513] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.047574] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.117871] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.294113] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.568918] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.638035] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.750471] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.826384] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.842932] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.871777] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.906338] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.932086] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1407.965837] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.017768] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.038232] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.101126] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.138781] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.247486] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.289800] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.334667] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.364528] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.408330] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.427674] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.475722] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.493930] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.571651] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.596670] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.642667] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.660186] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.678013] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.696222] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.761617] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.790217] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.809468] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.826894] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.845777] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1408.869130] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.022430] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.183643] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.208595] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.231373] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.263971] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.295853] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.327311] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.354358] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.486702] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.517466] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.684272] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.710502] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.784663] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.808431] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.858951] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1409.896753] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.114833] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.137946] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.157403] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.184151] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.206036] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.225161] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.259087] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.280125] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.299218] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.393885] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.417669] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.536335] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.561614] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.674163] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.716171] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.738663] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.787500] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.939453] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.957543] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.976633] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1410.997749] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.039231] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 20s idle [ 1411.160351] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.195573] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.258654] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.281371] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.305764] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.326021] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.361809] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.473151] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.645921] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.678435] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.696532] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.714166] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.732851] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.754188] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.774987] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.797195] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1411.873965] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.026269] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.065470] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.109238] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.128487] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.150361] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.209989] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.311564] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.335966] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.354063] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.373047] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.397246] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.593622] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.611362] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.630683] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.647487] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.665377] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.695647] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.726408] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.745505] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.781731] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1412.806146] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.038170] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.081107] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.132485] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.159809] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.240148] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.266068] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.293909] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.320812] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.354453] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.440801] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.459313] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.561602] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1413.628023] rw_seq_cst_vs_d (33016): drop_caches: 3 [ 1417.298446] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 01:02:43 (1776920563) [ 1420.544121] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 01:02:46 (1776920566) [ 1423.757433] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 01:02:50 (1776920570) [ 1437.100928] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 01:03:03 (1776920583) [ 1437.336940] bash (35509): drop_caches: 3 [ 1440.477670] bash (35509): drop_caches: 3 [ 1443.583460] bash (35509): drop_caches: 3 [ 1446.652157] bash (35509): drop_caches: 3 [ 1449.831851] bash (35509): drop_caches: 3 [ 1452.911164] bash (35509): drop_caches: 3 [ 1456.796607] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 01:03:23 (1776920603) [ 1462.530772] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 01:03:28 (1776920608) [ 1480.553110] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 01:03:46 (1776920626) [ 1483.994747] Lustre: DEBUG MARKER: loop 5 [ 1485.960699] Lustre: DEBUG MARKER: loop 10 [ 1487.995111] Lustre: DEBUG MARKER: loop 15 [ 1490.234936] Lustre: DEBUG MARKER: loop 20 [ 1493.711735] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 01:04:00 (1776920640) [ 1496.407427] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 01:04:02 (1776920642) [ 1499.297624] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 01:04:05 (1776920645) [ 1513.439275] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: disconnect after 20s idle [ 1513.442378] Lustre: Skipped 1 previous similar message [ 1563.535500] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 01:05:09 (1776920709) [ 1566.188411] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 01:05:12 (1776920712) [ 1568.642975] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 01:05:15 (1776920715) [ 1571.134499] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 01:05:17 (1776920717) [ 1573.742654] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 01:05:20 (1776920720) [ 1577.037891] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 01:05:23 (1776920723) [ 1581.593020] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 01:05:28 (1776920728) [ 1585.067921] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1585.683530] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 01:05:32 (1776920732) [ 1590.120111] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 01:05:36 (1776920736) [ 1590.256599] Lustre: *** cfs_fail_loc=314, val=0*** [ 1591.263351] Lustre: *** cfs_fail_loc=314, val=0*** [ 1591.265744] Lustre: Skipped 2 previous similar messages [ 1593.540694] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 01:05:40 (1776920740) [ 1598.780149] Lustre: *** cfs_fail_loc=314, val=0*** [ 1598.807788] LustreError: lustre-OST0000-osc-ffff9d5298c12800: operation ldlm_enqueue to node 192.168.203.104@tcp failed: rc = -107 [ 1598.811923] Lustre: lustre-OST0000-osc-ffff9d5298c12800: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1598.820482] LustreError: lustre-OST0000-osc-ffff9d5298c12800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1598.825374] LustreError: 46474:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff9d5298c12800: namespace resource [0x280000400:0x6:0x0].0x0 (ffff9d52915f0400) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1598.833187] Lustre: lustre-OST0000-osc-ffff9d5298c12800: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 1601.422071] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 01:05:47 (1776920747) [ 1601.490218] LustreError: 47064:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1604.511547] LustreError: 47064:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1606.803678] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 01:05:53 (1776920753) [ 1609.881723] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 01:05:56 (1776920756) [ 1613.400616] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1613.987743] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 01:06:00 (1776920760) [ 1614.620501] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1615.218066] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 01:06:01 (1776920761) [ 1620.961365] LustreError: MGC192.168.203.104@tcp: Connection to MGS (at 192.168.203.104@tcp) was lost; in progress operations using this service will fail [ 1620.965783] Lustre: lustre-MDT0000-mdc-ffff9d52a07bb800: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1620.968101] Lustre: Evicted from MGS (at 192.168.203.104@tcp) after server handle changed from 0x1bcf35080a96bcc7 to 0x1bcf35080aa851e3 [ 1620.977555] Lustre: MGC192.168.203.104@tcp: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 1624.751893] Lustre: lustre-MDT0000-mdc-ffff9d52a07bb800: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 1636.779865] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 01:06:23 (1776920783) [ 1655.841527] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 01:06:42 (1776920802) [ 1662.331496] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 01:06:48 (1776920808) [ 1712.060689] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1712.066073] Lustre: Skipped 1 previous similar message [ 1712.069536] LustreError: lustre-OST0000-osc-ffff9d5298c12800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1712.075167] LustreError: lustre-OST0000-osc-ffff9d52a07bb800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1712.075696] Lustre: lustre-OST0000-osc-ffff9d5298c12800: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 1712.084155] Lustre: Skipped 2 previous similar messages [ 1727.420335] Lustre: lustre-OST0001-osc-ffff9d52a07bb800: Connection to lustre-OST0001 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1727.427246] Lustre: Skipped 1 previous similar message [ 1727.430237] LustreError: lustre-OST0001-osc-ffff9d52a07bb800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1727.435262] Lustre: lustre-OST0001-osc-ffff9d52a07bb800: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 1733.599286] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: disconnect after 21s idle [ 1733.602594] Lustre: Skipped 3 previous similar messages [ 1738.288636] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1738.927893] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid in IDLE state after 0 sec [ 1740.401543] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1740.910199] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid in FULL state after 0 sec [ 1742.584102] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1743.168644] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid in IDLE state after 0 sec [ 1744.505435] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1745.118524] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid in FULL state after 0 sec [ 1748.707904] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1749.210856] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5298c12800.ost_server_uuid in IDLE state after 0 sec [ 1750.465931] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid 50 [ 1750.991899] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5298c12800.ost_server_uuid in FULL state after 0 sec [ 1751.590918] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 01:08:18 (1776920898) [ 1752.477976] Lustre: DEBUG MARKER: Race attempt 0 [ 1754.040526] Lustre: DEBUG MARKER: Wait for 58397 58531 for 60 sec... [ 1816.305541] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 01:09:22 (1776920962) [ 1821.494446] Lustre: DEBUG MARKER: start test - cycle (0) [ 1836.263482] Lustre: DEBUG MARKER: start test - cycle (1) [ 1851.098676] Lustre: DEBUG MARKER: start test - cycle (2) [ 1867.089392] Lustre: DEBUG MARKER: start test - cycle (3) [ 1882.065771] Lustre: DEBUG MARKER: start test - cycle (4) [ 1897.364617] Lustre: DEBUG MARKER: start test - cycle (5) [ 1919.674542] Lustre: DEBUG MARKER: start test - cycle (6) [ 1923.039305] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 21s idle [ 1923.047108] Lustre: Skipped 2 previous similar messages [ 1945.041385] Lustre: DEBUG MARKER: start test - cycle (7) [ 1965.150675] Lustre: DEBUG MARKER: start test - cycle (8) [ 1984.729551] Lustre: DEBUG MARKER: start test - cycle (9) [ 2005.016726] Lustre: DEBUG MARKER: start test - cycle (10) [ 2010.079509] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 21s idle [ 2029.058186] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 01:12:55 (1776921175) [ 2085.607688] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 01:13:51 (1776921231) [ 2088.913385] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 01:13:55 (1776921235) [ 2093.702572] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 01:13:59 (1776921239) [ 2099.365580] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 01:14:05 (1776921245) [ 2099.663546] Lustre: *** cfs_fail_loc=411, val=0*** [ 2104.052782] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2105.232860] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 01:14:11 (1776921251) [ 2116.961149] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 01:14:23 (1776921263) [ 2130.381420] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 01:14:36 (1776921276) [ 2138.081212] Lustre: lustre-OST0001-osc-ffff9d52a07bb800: disconnect after 25s idle [ 2138.083670] Lustre: Skipped 2 previous similar messages [ 2142.392443] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 01:14:48 (1776921288) [ 2153.243658] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 01:14:59 (1776921299) [ 2162.463472] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 01:15:08 (1776921308) [ 2171.776272] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 01:15:17 (1776921317) [ 2181.269455] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 01:15:27 (1776921327) [ 2190.918461] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 01:15:36 (1776921336) [ 2200.704946] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 01:15:46 (1776921346) [ 2210.145278] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 01:15:56 (1776921356) [ 2219.264890] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 01:16:05 (1776921365) [ 2230.151808] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 01:16:15 (1776921375) [ 2849.759284] Lustre: lustre-OST0000-osc-ffff9d5298c12800: disconnect after 22s idle [ 2849.762655] Lustre: Skipped 4 previous similar messages [ 3060.500333] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 01:30:06 (1776922206) [ 3067.194636] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 01:30:13 (1776922213) [ 3073.829701] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 01:30:20 (1776922220) [ 3080.576092] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 01:30:26 (1776922226) [ 3086.849844] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 01:30:33 (1776922233) [ 3093.303521] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 01:30:39 (1776922239) [ 3100.331375] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 01:30:46 (1776922246) [ 3107.093287] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 01:30:53 (1776922253) [ 3114.491392] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 01:31:00 (1776922260) [ 3164.645076] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 01:31:50 (1776922310) [ 3171.885916] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 01:31:58 (1776922318) [ 3178.737707] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 01:32:05 (1776922325) [ 3185.567686] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 01:32:11 (1776922331) [ 3192.503488] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 01:32:18 (1776922338) [ 3199.886925] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 01:32:26 (1776922346) [ 3206.948485] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 01:32:33 (1776922353) [ 3214.291227] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 01:32:40 (1776922360) [ 3221.210216] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 01:32:47 (1776922367) [ 3279.405361] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 01:33:45 (1776922425) [ 3499.999192] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: disconnect after 20s idle [ 3500.002375] Lustre: Skipped 6 previous similar messages [ 3758.127056] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 01:41:44 (1776922904) [ 3763.565800] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 01:41:49 (1776922909) [ 3768.822833] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 01:41:55 (1776922915) [ 3773.964857] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 01:42:00 (1776922920) [ 3779.222768] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 01:42:05 (1776922925) [ 3784.411612] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 01:42:10 (1776922930) [ 3789.762605] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 01:42:16 (1776922936) [ 3795.415412] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 01:42:21 (1776922941) [ 3800.927022] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 01:42:27 (1776922947) [ 3806.426484] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 01:42:32 (1776922952) [ 3851.029138] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 01:43:17 (1776922997) [ 3856.500351] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 01:43:22 (1776923002) [ 3861.914210] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 01:43:28 (1776923008) [ 3867.488080] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 01:43:33 (1776923013) [ 3872.550392] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 01:43:39 (1776923019) [ 3877.846136] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 01:43:44 (1776923024) [ 3883.182639] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 01:43:49 (1776923029) [ 3887.804739] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 01:43:54 (1776923034) [ 3893.186567] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 01:43:59 (1776923039) [ 4333.911744] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 01:51:20 (1776923480) [ 4339.043335] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 01:51:25 (1776923485) [ 4344.239064] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 01:51:30 (1776923490) [ 4349.511678] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 01:51:36 (1776923496) [ 4355.090581] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 01:51:41 (1776923501) [ 4360.561882] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 01:51:47 (1776923507) [ 4365.771644] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 01:51:52 (1776923512) [ 4370.981414] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 01:51:57 (1776923517) [ 4376.077782] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 01:52:02 (1776923522) [ 4381.152187] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 01:52:07 (1776923527) [ 4386.328605] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 01:52:12 (1776923532) [ 4392.495076] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 01:52:18 (1776923538) [ 4397.575405] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 01:52:24 (1776923544) [ 4402.625496] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 01:52:29 (1776923549) [ 4407.790267] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 01:52:34 (1776923554) [ 4412.987197] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 01:52:39 (1776923559) [ 4419.326324] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 01:52:45 (1776923565) [ 4419.421762] LustreError: 22647:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4421.503132] LustreError: 22647:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4426.690776] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 01:52:53 (1776923573) [ 4430.803366] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 01:52:57 (1776923577) [ 4430.889787] LustreError: 240024:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4434.951115] LustreError: 240024:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4434.957740] LustreError: 240024:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4439.015143] LustreError: 240024:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4439.031291] LustreError: 240033:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4443.095096] LustreError: 240033:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4445.424216] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 01:53:11 (1776923591) [ 4451.814234] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 01:53:18 (1776923598) [ 4454.796555] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 01:53:21 (1776923601) [ 4458.812806] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 01:53:25 (1776923605) [ 4465.631232] Lustre: lustre-OST0000-osc-ffff9d52a07bb800: disconnect after 20s idle [ 4465.634725] Lustre: Skipped 2 previous similar messages [ 4482.857647] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 01:53:49 (1776923629) [ 4490.319893] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 01:53:56 (1776923636) [ 4497.711234] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 01:54:04 (1776923644) [ 4510.440592] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 01:54:16 (1776923656) [ 4519.831169] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 01:54:26 (1776923666) [ 4532.842742] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 01:54:39 (1776923679) [ 4545.624871] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 01:54:52 (1776923692) [ 4559.858337] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 01:55:06 (1776923706) [ 4567.039682] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 01:55:13 (1776923713) [ 4596.466226] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 01:55:42 (1776923742) [ 4598.707252] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4600.980138] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 01:55:47 (1776923747) [ 4603.905401] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 01:55:50 (1776923750) [ 4606.196539] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 01:55:52 (1776923752) [ 4608.390526] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 01:55:54 (1776923754) [ 4619.228373] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 01:56:05 (1776923765) [ 4633.039448] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 01:56:19 (1776923779) [ 4635.175684] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 01:56:21 (1776923781) [ 4637.317667] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 01:56:23 (1776923783) [ 4640.390852] LustreError: lustre-MDT0000-mdc-ffff9d5298c12800: operation ldlm_enqueue to node 192.168.203.104@tcp failed: rc = -35 [ 4643.404625] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 01:56:29 (1776923789) [ 4643.542896] LustreError: 2409:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4645.559102] LustreError: 2409:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4650.533235] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 01:56:37 (1776923797) [ 4696.944142] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 01:57:23 (1776923843) [ 4699.812774] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 01:57:26 (1776923846) [ 4703.823712] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 01:57:30 (1776923850) [ 4708.642413] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 01:57:35 (1776923855) [ 4713.650093] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 01:57:40 (1776923860) [ 4720.678686] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 01:57:47 (1776923867) [ 4728.060860] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 01:57:54 (1776923874) [ 4731.645478] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 01:57:58 (1776923878) [ 4735.400311] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 01:58:01 (1776923881) [ 4742.508822] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 01:58:08 (1776923888) [ 4784.933660] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 01:58:51 (1776923931) [ 4897.916139] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 02:00:44 (1776924044) [ 5011.497632] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 02:02:37 (1776924157) [ 5115.871184] Lustre: lustre-OST0001-osc-ffff9d52a07bb800: disconnect after 23s idle [ 5115.873826] Lustre: Skipped 10 previous similar messages [ 5287.849226] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 02:07:14 (1776924434) [ 5315.811794] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 02:07:42 (1776924462) [ 5345.361286] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 02:08:11 (1776924491) [ 5371.528680] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 02:08:38 (1776924518) [ 5431.386949] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 02:09:37 (1776924577) [ 5483.277481] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 02:10:29 (1776924629) [ 5576.363404] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 02:12:02 (1776924722) [ 5577.406374] LustreError: 287544:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a07bb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5577.428125] Lustre: Unmounted lustre-client [ 5578.380621] LustreError: 287557:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298c12800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5578.383694] LustreError: 287557:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5578.401663] Lustre: Unmounted lustre-client [ 5635.138863] Lustre: Mounted lustre-client [ 5636.646103] Lustre: Mounted lustre-client [ 5637.642853] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5697.320492] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 02:14:03 (1776924843) [ 5704.668618] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 02:14:11 (1776924851) [ 5707.789460] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 02:14:14 (1776924854) [ 5758.117560] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 02:15:04 (1776924904) [ 5793.616789] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 02:15:40 (1776924940) [ 5795.295250] Lustre: lustre-OST0001-osc-ffff9d529aa5f000: disconnect after 21s idle [ 5795.298516] Lustre: Skipped 13 previous similar messages [ 5797.259560] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 02:15:43 (1776924943) [ 5833.561475] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 02:16:20 (1776924980) [ 5844.714890] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 02:16:31 (1776924991) [ 5885.231775] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 02:17:11 (1776925031) [ 5888.002242] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 02:17:14 (1776925034) [ 5900.537638] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 02:17:27 (1776925047) [ 5904.004029] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 02:17:30 (1776925050) [ 5904.351593] LustreError: 307740:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x392:0x0]: rc = -5 [ 5904.356810] LustreError: 307740:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5904.917088] LustreError: 307790:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3a2:0x0]: rc = -5 [ 5904.920947] LustreError: 307790:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 12 previous similar messages [ 5904.923321] LustreError: 307790:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5904.925820] LustreError: 307790:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 12 previous similar messages [ 5905.988401] LustreError: 307891:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3c0:0x0]: rc = -5 [ 5905.991894] LustreError: 307891:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 29 previous similar messages [ 5905.994371] LustreError: 307891:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5905.997035] LustreError: 307891:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 29 previous similar messages [ 5908.018799] LustreError: 308104:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x402:0x0]: rc = -5 [ 5908.022275] LustreError: 308104:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 59 previous similar messages [ 5908.024609] LustreError: 308104:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5908.027426] LustreError: 308104:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 59 previous similar messages [ 5912.068448] LustreError: 308472:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x488:0x0]: rc = -5 [ 5912.073180] LustreError: 308472:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 107 previous similar messages [ 5912.076201] LustreError: 308472:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5912.079432] LustreError: 308472:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 107 previous similar messages [ 5920.101110] LustreError: 309261:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x59a:0x0]: rc = -5 [ 5920.104289] LustreError: 309261:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 227 previous similar messages [ 5920.106925] LustreError: 309261:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5920.109689] LustreError: 309261:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 227 previous similar messages [ 5932.633939] LustreError: 310467:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff9d5298d47800: inode [0x2000013a1:0x754:0x0] mdc close failed: rc = -2 [ 5936.141302] LustreError: 310789:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x7ca:0x0]: rc = -5 [ 5936.144432] LustreError: 310789:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 437 previous similar messages [ 5936.146897] LustreError: 310789:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5936.149544] LustreError: 310789:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 437 previous similar messages [ 5966.139985] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 02:18:32 (1776925112) [ 5968.221861] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 02:18:34 (1776925114) [ 6006.264572] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 02:19:12 (1776925152) [ 6006.721063] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6007.251498] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 02:19:13 (1776925153) [ 6041.705185] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 02:19:48 (1776925188) [ 6043.848461] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 02:19:50 (1776925190) [ 6165.954490] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 02:21:52 (1776925312) [ 6173.166298] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 02:21:59 (1776925319) [ 6175.653676] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 02:22:02 (1776925322) [ 6357.921805] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 02:25:04 (1776925504) [ 6532.576103] Lustre: lustre-OST0000-osc-ffff9d529aa5f000: disconnect after 20s idle [ 6532.579501] Lustre: Skipped 1 previous similar message [ 6540.103753] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 02:28:06 (1776925686) [ 6542.172163] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 02:28:08 (1776925688) [ 6550.965691] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 02:28:17 (1776925697) [ 6551.014552] Lustre: DEBUG MARKER: write [ 6551.033613] LustreError: 289799:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6553.042062] Lustre: DEBUG MARKER: kill 378376 [ 6553.044396] LustreError: 378376:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6556.135103] LustreError: 289799:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6559.079084] LustreError: 378376:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6561.250512] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 02:28:27 (1776925707) [ 6563.417715] LustreError: 378989:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6573.511109] LustreError: 378989:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6575.789577] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 02:28:42 (1776925722) [ 6575.891470] LustreError: 379576:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6577.975142] LustreError: 379576:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 6584.039068] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 02:28:50 (1776925730) [ 6584.549165] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6585.100690] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 02:28:51 (1776925731) [ 6587.667830] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 02:28:54 (1776925734) [ 6589.742739] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 02:28:56 (1776925736) [ 6591.909933] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 02:28:58 (1776925738) [ 6594.023175] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 02:29:00 (1776925740) [ 6596.133897] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 02:29:02 (1776925742) [ 6598.290758] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 02:29:04 (1776925744) [ 6601.515363] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 02:29:08 (1776925748) [ 6604.237711] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 02:29:10 (1776925750) [ 6604.911493] Lustre: *** cfs_fail_loc=415, val=0*** [ 6611.746067] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 02:29:18 (1776925758) [ 6630.894142] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 02:29:37 (1776925777) [ 6630.989968] LustreError: 289799:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6630.992418] LustreError: 289799:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 6636.087109] LustreError: 289953:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6636.087108] LustreError: 289799:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 6636.087125] LustreError: 289799:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 1 previous similar message [ 6636.089970] LustreError: 289953:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 6653.531808] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 02:30:00 (1776925800) [ 6655.883427] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 02:30:02 (1776925802) [ 6658.130533] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 02:30:04 (1776925804) [ 6660.194368] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 02:30:06 (1776925806) [ 6664.345622] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 02:30:10 (1776925810) [ 6672.538404] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 02:30:19 (1776925819) [ 6672.671993] LustreError: 390309:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6672.674517] LustreError: 390309:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 6676.735070] LustreError: 390309:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6676.737252] LustreError: 390309:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 6678.922441] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 02:30:25 (1776925825) [ 6680.332406] LustreError: 391018:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d529aa5f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6680.335507] LustreError: 391018:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6680.358732] Lustre: Unmounted lustre-client [ 6680.915104] LustreError: 391038:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298d47800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6680.918485] LustreError: 391038:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6680.983856] Lustre: Unmounted lustre-client [ 6681.450442] Lustre: DEBUG MARKER: Iteration 1 [ 6681.563854] LustreError: 391200:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6681.564266] LustreError: 391201:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6681.568898] LustreError: 391200:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6681.634793] Lustre: Mounted lustre-client [ 6682.191285] LustreError: 391314:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298c17000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6682.194392] LustreError: 391314:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6682.263402] Lustre: Unmounted lustre-client [ 6683.200805] Key type lgssc unregistered [ 6683.309537] LNet: 391558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6683.312573] LNetError: 391558:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6683.321990] LNet: Removed LNI 192.168.203.4@tcp [ 6683.609112] Key type .llcrypt unregistered [ 6683.610179] Key type ._llcrypt unregistered [ 6683.991897] Key type ._llcrypt registered [ 6683.995678] Key type .llcrypt registered [ 6684.122959] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6684.127572] alg: No test for adler32 (adler32-zlib) [ 6685.101321] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6685.375113] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6686.983109] Key type lgssc registered [ 6687.516035] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6691.599767] Lustre: DEBUG MARKER: Iteration 2 [ 6691.704967] LustreError: 392390:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6691.705050] LustreError: 392391:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6691.709648] LustreError: 392390:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6692.766915] Lustre: Mounted lustre-client [ 6692.768301] Lustre: Skipped 1 previous similar message [ 6693.190339] LustreError: 392505:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a0677800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6693.194126] LustreError: 392505:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6693.252561] Lustre: Unmounted lustre-client [ 6694.337345] Key type lgssc unregistered [ 6694.459488] LNet: 392748:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6694.462072] LNetError: 392748:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6694.472726] LNet: Removed LNI 192.168.203.4@tcp [ 6694.694091] Key type .llcrypt unregistered [ 6694.695249] Key type ._llcrypt unregistered [ 6694.932404] Key type ._llcrypt registered [ 6694.934324] Key type .llcrypt registered [ 6695.134332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6695.138807] alg: No test for adler32 (adler32-zlib) [ 6695.993471] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6696.072572] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6697.655129] Key type lgssc registered [ 6698.017669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6701.688216] Lustre: DEBUG MARKER: Iteration 3 [ 6701.782075] LustreError: 393580:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6701.782106] LustreError: 393581:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6701.786429] LustreError: 393580:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6702.838277] Lustre: Mounted lustre-client [ 6703.258166] LustreError: 393694:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298c14800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6703.262222] LustreError: 393694:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6703.343138] Lustre: Unmounted lustre-client [ 6704.279309] Key type lgssc unregistered [ 6704.399465] LNet: 393938:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6704.402046] LNetError: 393938:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6704.408715] LNet: Removed LNI 192.168.203.4@tcp [ 6704.634100] Key type .llcrypt unregistered [ 6704.635489] Key type ._llcrypt unregistered [ 6704.929800] Key type ._llcrypt registered [ 6704.934597] Key type .llcrypt registered [ 6705.115610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6705.120570] alg: No test for adler32 (adler32-zlib) [ 6705.977826] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6706.059076] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6707.639117] Key type lgssc registered [ 6707.995817] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6711.421527] Lustre: DEBUG MARKER: Iteration 4 [ 6711.527467] LustreError: 394770:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6711.527540] LustreError: 394771:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6711.531525] LustreError: 394770:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6712.583195] Lustre: Mounted lustre-client [ 6712.584810] Lustre: Skipped 1 previous similar message [ 6713.013981] LustreError: 394883:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298c12800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6713.018056] LustreError: 394883:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6713.081077] Lustre: Unmounted lustre-client [ 6714.067399] Key type lgssc unregistered [ 6714.201956] LNet: 395126:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6714.207379] LNetError: 395126:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6714.218405] LNet: Removed LNI 192.168.203.4@tcp [ 6714.570164] Key type .llcrypt unregistered [ 6714.572334] Key type ._llcrypt unregistered [ 6714.895302] Key type ._llcrypt registered [ 6714.896301] Key type .llcrypt registered [ 6715.044671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6715.049209] alg: No test for adler32 (adler32-zlib) [ 6715.949587] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6716.075342] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6717.679169] Key type lgssc registered [ 6718.203839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6722.145208] Lustre: DEBUG MARKER: Iteration 5 [ 6722.261328] LustreError: 395958:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6722.261833] LustreError: 395959:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6722.267977] LustreError: 395958:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6723.319601] Lustre: Mounted lustre-client [ 6723.321514] Lustre: Skipped 1 previous similar message [ 6723.740099] LustreError: 396073:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528a646800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6723.743788] LustreError: 396073:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6723.846839] Lustre: Unmounted lustre-client [ 6724.943568] Key type lgssc unregistered [ 6725.090585] LNet: 396316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6725.093356] LNetError: 396316:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6725.103660] LNet: Removed LNI 192.168.203.4@tcp [ 6725.331152] Key type .llcrypt unregistered [ 6725.332336] Key type ._llcrypt unregistered [ 6725.547448] Key type ._llcrypt registered [ 6725.548522] Key type .llcrypt registered [ 6725.749993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6725.754693] alg: No test for adler32 (adler32-zlib) [ 6726.608685] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6726.690054] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6728.279130] Key type lgssc registered [ 6728.624044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6732.390157] Lustre: DEBUG MARKER: Iteration 6 [ 6732.504321] LustreError: 397148:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6732.504547] LustreError: 397149:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6732.509548] LustreError: 397148:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6733.580211] Lustre: Mounted lustre-client [ 6734.137096] LustreError: 397264:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5290d5a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6734.140216] LustreError: 397264:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6734.191829] Lustre: Unmounted lustre-client [ 6735.225802] Key type lgssc unregistered [ 6735.342711] LNet: 397509:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6735.345417] LNetError: 397509:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6735.355194] LNet: Removed LNI 192.168.203.4@tcp [ 6735.603222] Key type .llcrypt unregistered [ 6735.604916] Key type ._llcrypt unregistered [ 6735.874410] Key type ._llcrypt registered [ 6735.875545] Key type .llcrypt registered [ 6736.112855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6736.118600] alg: No test for adler32 (adler32-zlib) [ 6736.964827] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6737.052712] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6738.631141] Key type lgssc registered [ 6739.072758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6742.728676] Lustre: DEBUG MARKER: Iteration 7 [ 6742.828594] LustreError: 398341:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6742.829701] LustreError: 398342:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6742.832780] LustreError: 398341:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6743.904436] Lustre: Mounted lustre-client [ 6744.480800] LustreError: 398455:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52ab19d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6744.485390] LustreError: 398455:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6744.552195] Lustre: Unmounted lustre-client [ 6745.608503] Key type lgssc unregistered [ 6745.731600] LNet: 398698:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6745.734809] LNetError: 398698:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6745.742894] LNet: Removed LNI 192.168.203.4@tcp [ 6745.997123] Key type .llcrypt unregistered [ 6745.998459] Key type ._llcrypt unregistered [ 6746.304212] Key type ._llcrypt registered [ 6746.317000] Key type .llcrypt registered [ 6746.520759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6746.527103] alg: No test for adler32 (adler32-zlib) [ 6747.402683] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6747.496184] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6749.087313] Key type lgssc registered [ 6749.570412] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6753.741161] Lustre: DEBUG MARKER: Iteration 8 [ 6753.842613] LustreError: 399531:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6753.842672] LustreError: 399532:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6753.846906] LustreError: 399531:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6754.902488] Lustre: Mounted lustre-client [ 6755.354293] LustreError: 399648:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52aa69d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6755.357703] LustreError: 399648:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6755.412473] Lustre: Unmounted lustre-client [ 6756.362145] Key type lgssc unregistered [ 6756.478511] LNet: 399891:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6756.481247] LNetError: 399891:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6756.491862] LNet: Removed LNI 192.168.203.4@tcp [ 6756.719118] Key type .llcrypt unregistered [ 6756.720643] Key type ._llcrypt unregistered [ 6756.978963] Key type ._llcrypt registered [ 6756.980083] Key type .llcrypt registered [ 6757.137808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6757.144290] alg: No test for adler32 (adler32-zlib) [ 6757.990663] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6758.079746] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6759.663184] Key type lgssc registered [ 6760.152078] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6764.189460] Lustre: DEBUG MARKER: Iteration 9 [ 6764.304172] LustreError: 400723:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6764.304192] LustreError: 400724:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6764.309628] LustreError: 400723:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6765.365946] Lustre: Mounted lustre-client [ 6765.825259] LustreError: 400836:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5291626000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6765.830979] LustreError: 400836:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6765.878723] Lustre: Unmounted lustre-client [ 6766.809501] Key type lgssc unregistered [ 6766.937376] LNet: 401079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6766.941154] LNetError: 401079:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6766.950753] LNet: Removed LNI 192.168.203.4@tcp [ 6767.180090] Key type .llcrypt unregistered [ 6767.181266] Key type ._llcrypt unregistered [ 6767.438602] Key type ._llcrypt registered [ 6767.440803] Key type .llcrypt registered [ 6767.664889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6767.671276] alg: No test for adler32 (adler32-zlib) [ 6768.526583] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6768.611080] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6770.191131] Key type lgssc registered [ 6770.564304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6774.498003] Lustre: DEBUG MARKER: Iteration 10 [ 6774.622374] LustreError: 401911:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6774.622407] LustreError: 401912:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6774.627281] LustreError: 401911:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6775.678991] Lustre: Mounted lustre-client [ 6776.131295] LustreError: 402023:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528a647000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6776.135468] LustreError: 402023:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6776.196420] Lustre: Unmounted lustre-client [ 6777.151265] Key type lgssc unregistered [ 6777.263500] LNet: 402266:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6777.265813] LNetError: 402266:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6777.273719] LNet: Removed LNI 192.168.203.4@tcp [ 6777.513108] Key type .llcrypt unregistered [ 6777.514199] Key type ._llcrypt unregistered [ 6777.792591] Key type ._llcrypt registered [ 6777.793602] Key type .llcrypt registered [ 6777.973034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6777.978142] alg: No test for adler32 (adler32-zlib) [ 6778.830347] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6778.907413] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6780.487096] Key type lgssc registered [ 6780.822079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6784.131127] Lustre: DEBUG MARKER: Iteration 11 [ 6784.237674] LustreError: 403098:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6784.237934] LustreError: 403099:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6784.242306] LustreError: 403098:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6785.296735] Lustre: Mounted lustre-client [ 6785.298799] Lustre: Skipped 1 previous similar message [ 6785.730948] LustreError: 403209:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a9695800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6785.734065] LustreError: 403209:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6785.784133] Lustre: Unmounted lustre-client [ 6786.851212] Key type lgssc unregistered [ 6786.967553] LNet: 403452:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6786.970338] LNetError: 403452:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6786.977811] LNet: Removed LNI 192.168.203.4@tcp [ 6787.213162] Key type .llcrypt unregistered [ 6787.214574] Key type ._llcrypt unregistered [ 6787.487431] Key type ._llcrypt registered [ 6787.488594] Key type .llcrypt registered [ 6787.670801] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6787.675811] alg: No test for adler32 (adler32-zlib) [ 6788.576371] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6788.680317] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6790.271226] Key type lgssc registered [ 6790.706183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6794.416911] Lustre: DEBUG MARKER: Iteration 12 [ 6794.541145] LustreError: 404285:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6794.542182] LustreError: 404284:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6794.547661] LustreError: 404285:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6795.602329] Lustre: Mounted lustre-client [ 6795.604165] Lustre: Skipped 1 previous similar message [ 6796.103690] LustreError: 404399:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5290c01000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6796.107912] LustreError: 404399:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6796.158115] Lustre: Unmounted lustre-client [ 6797.063664] Key type lgssc unregistered [ 6797.170474] LNet: 404641:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6797.172894] LNetError: 404641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6797.180700] LNet: Removed LNI 192.168.203.4@tcp [ 6797.395148] Key type .llcrypt unregistered [ 6797.396257] Key type ._llcrypt unregistered [ 6797.692041] Key type ._llcrypt registered [ 6797.693707] Key type .llcrypt registered [ 6797.846794] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6797.851300] alg: No test for adler32 (adler32-zlib) [ 6798.707411] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6798.795576] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6800.383241] Key type lgssc registered [ 6800.908578] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6804.400906] Lustre: DEBUG MARKER: Iteration 13 [ 6804.536319] LustreError: 405473:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6804.536374] LustreError: 405474:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6804.541148] LustreError: 405473:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6805.594265] Lustre: Mounted lustre-client [ 6806.019458] LustreError: 405587:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a1137800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6806.022979] LustreError: 405587:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6806.087576] Lustre: Unmounted lustre-client [ 6807.053619] Key type lgssc unregistered [ 6807.200688] LNet: 405831:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6807.205630] LNetError: 405831:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6807.218206] LNet: Removed LNI 192.168.203.4@tcp [ 6807.464178] Key type .llcrypt unregistered [ 6807.465690] Key type ._llcrypt unregistered [ 6807.747505] Key type ._llcrypt registered [ 6807.749669] Key type .llcrypt registered [ 6807.923016] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6807.927627] alg: No test for adler32 (adler32-zlib) [ 6808.791518] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6808.881855] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6810.463125] Key type lgssc registered [ 6810.828849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6814.859530] Lustre: DEBUG MARKER: Iteration 14 [ 6814.992353] LustreError: 406663:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6814.992428] LustreError: 406664:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6814.996500] LustreError: 406663:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6816.060733] Lustre: Mounted lustre-client [ 6816.645508] LustreError: 406777:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52881a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6816.706978] Lustre: Unmounted lustre-client [ 6817.682867] Key type lgssc unregistered [ 6817.795759] LNet: 407018:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6817.800245] LNetError: 407018:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6817.810201] LNet: Removed LNI 192.168.203.4@tcp [ 6818.050122] Key type .llcrypt unregistered [ 6818.051221] Key type ._llcrypt unregistered [ 6818.314878] Key type ._llcrypt registered [ 6818.322569] Key type .llcrypt registered [ 6818.501844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6818.507220] alg: No test for adler32 (adler32-zlib) [ 6819.372451] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6819.461499] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6821.047140] Key type lgssc registered [ 6821.431553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6824.938716] Lustre: DEBUG MARKER: Iteration 15 [ 6825.029534] LustreError: 407850:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6825.029585] LustreError: 407851:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6825.034499] LustreError: 407850:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6826.087536] Lustre: Mounted lustre-client [ 6826.089307] Lustre: Skipped 1 previous similar message [ 6826.529824] LustreError: 407964:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5290d58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6826.533724] LustreError: 407964:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6826.616347] Lustre: Unmounted lustre-client [ 6827.568331] Key type lgssc unregistered [ 6827.689709] LNet: 408208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6827.694212] LNetError: 408208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6827.703052] LNet: Removed LNI 192.168.203.4@tcp [ 6827.964108] Key type .llcrypt unregistered [ 6827.965428] Key type ._llcrypt unregistered [ 6828.288222] Key type ._llcrypt registered [ 6828.289345] Key type .llcrypt registered [ 6828.411107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6828.415622] alg: No test for adler32 (adler32-zlib) [ 6829.276591] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6829.360550] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6830.935103] Key type lgssc registered [ 6831.326481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6835.514913] Lustre: DEBUG MARKER: Iteration 16 [ 6835.614984] LustreError: 409040:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6835.615081] LustreError: 409041:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6835.620207] LustreError: 409040:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6836.681271] Lustre: Mounted lustre-client [ 6836.682791] Lustre: Skipped 1 previous similar message [ 6837.196749] LustreError: 409152:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5298c10800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6837.201591] LustreError: 409152:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6837.288105] Lustre: Unmounted lustre-client [ 6837.289750] Lustre: Skipped 1 previous similar message [ 6838.193773] Key type lgssc unregistered [ 6838.305278] LNet: 409396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6838.308102] LNetError: 409396:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6838.313742] LNet: Removed LNI 192.168.203.4@tcp [ 6838.545102] Key type .llcrypt unregistered [ 6838.546322] Key type ._llcrypt unregistered [ 6838.828050] Key type ._llcrypt registered [ 6838.829346] Key type .llcrypt registered [ 6839.005985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6839.011038] alg: No test for adler32 (adler32-zlib) [ 6839.872346] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6839.953081] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6841.527203] Key type lgssc registered [ 6841.921333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6845.418319] Lustre: DEBUG MARKER: Iteration 17 [ 6845.525171] LustreError: 410236:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6845.525180] LustreError: 410237:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6845.529745] LustreError: 410236:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6846.585228] Lustre: Mounted lustre-client [ 6846.586456] Lustre: Skipped 1 previous similar message [ 6847.075280] LustreError: 410351:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5286b2a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6847.078828] LustreError: 410351:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6847.133107] Lustre: Unmounted lustre-client [ 6847.134844] Lustre: Skipped 1 previous similar message [ 6848.040636] Key type lgssc unregistered [ 6848.151349] LNet: 410593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6848.154071] LNetError: 410593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6848.162778] LNet: Removed LNI 192.168.203.4@tcp [ 6848.388137] Key type .llcrypt unregistered [ 6848.389603] Key type ._llcrypt unregistered [ 6848.600741] Key type ._llcrypt registered [ 6848.602040] Key type .llcrypt registered [ 6848.809567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6848.816169] alg: No test for adler32 (adler32-zlib) [ 6849.664943] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6849.741418] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6851.319107] Key type lgssc registered [ 6851.683218] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6855.330984] Lustre: DEBUG MARKER: Iteration 18 [ 6855.453258] LustreError: 411425:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6855.453286] LustreError: 411426:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6855.457852] LustreError: 411425:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6856.510394] Lustre: Mounted lustre-client [ 6856.938985] LustreError: 411541:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528605d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6856.942433] LustreError: 411541:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6857.031366] Lustre: Unmounted lustre-client [ 6857.033212] Lustre: Skipped 1 previous similar message [ 6858.268444] Key type lgssc unregistered [ 6858.388617] LNet: 411785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6858.392257] LNetError: 411785:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6858.401039] LNet: Removed LNI 192.168.203.4@tcp [ 6858.755138] Key type .llcrypt unregistered [ 6858.757347] Key type ._llcrypt unregistered [ 6859.062799] Key type ._llcrypt registered [ 6859.063848] Key type .llcrypt registered [ 6859.247317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6859.251870] alg: No test for adler32 (adler32-zlib) [ 6860.173893] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6860.278343] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6861.863224] Key type lgssc registered [ 6862.401088] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6866.297220] Lustre: DEBUG MARKER: Iteration 19 [ 6866.394397] LustreError: 412618:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6866.394464] LustreError: 412619:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6866.398960] LustreError: 412618:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6867.451594] Lustre: Mounted lustre-client [ 6867.452962] Lustre: Skipped 1 previous similar message [ 6867.869768] LustreError: 412728:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5285843800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6867.873272] LustreError: 412728:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6867.924174] Lustre: Unmounted lustre-client [ 6868.853418] Key type lgssc unregistered [ 6868.965373] LNet: 412973:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6868.968835] LNetError: 412973:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6868.980687] LNet: Removed LNI 192.168.203.4@tcp [ 6869.250119] Key type .llcrypt unregistered [ 6869.251516] Key type ._llcrypt unregistered [ 6869.505278] Key type ._llcrypt registered [ 6869.507131] Key type .llcrypt registered [ 6869.696507] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6869.701045] alg: No test for adler32 (adler32-zlib) [ 6870.550843] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6870.631398] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6872.223156] Key type lgssc registered [ 6872.571506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6876.007914] Lustre: DEBUG MARKER: Iteration 20 [ 6876.129043] LustreError: 413806:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6876.129080] LustreError: 413805:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6876.135055] LustreError: 413806:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6877.198459] Lustre: Mounted lustre-client [ 6877.199654] Lustre: Skipped 1 previous similar message [ 6877.632619] LustreError: 413923:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5285129000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6877.732137] Lustre: Unmounted lustre-client [ 6878.594442] Key type lgssc unregistered [ 6878.706267] LNet: 414166:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6878.709043] LNetError: 414166:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6878.718644] LNet: Removed LNI 192.168.203.4@tcp [ 6878.939092] Key type .llcrypt unregistered [ 6878.940584] Key type ._llcrypt unregistered [ 6879.165466] Key type ._llcrypt registered [ 6879.166843] Key type .llcrypt registered [ 6879.369922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6879.374710] alg: No test for adler32 (adler32-zlib) [ 6880.224285] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6880.303643] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6881.879113] Key type lgssc registered [ 6882.237530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6885.775101] Lustre: DEBUG MARKER: Iteration 21 [ 6885.890234] LustreError: 414998:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6885.890272] LustreError: 414999:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6885.896311] LustreError: 414998:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6886.949444] Lustre: Mounted lustre-client [ 6886.950881] Lustre: Skipped 1 previous similar message [ 6887.391122] LustreError: 415110:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5286969800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6887.395109] LustreError: 415110:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6887.461073] Lustre: Unmounted lustre-client [ 6887.462803] Lustre: Skipped 1 previous similar message [ 6888.415253] Key type lgssc unregistered [ 6888.532508] LNet: 415354:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6888.534918] LNetError: 415354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6888.545703] LNet: Removed LNI 192.168.203.4@tcp [ 6888.769106] Key type .llcrypt unregistered [ 6888.770738] Key type ._llcrypt unregistered [ 6889.052212] Key type ._llcrypt registered [ 6889.056372] Key type .llcrypt registered [ 6889.243325] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6889.248083] alg: No test for adler32 (adler32-zlib) [ 6890.093673] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6890.167670] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6891.743123] Key type lgssc registered [ 6892.120409] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6895.398954] Lustre: DEBUG MARKER: Iteration 22 [ 6895.495996] LustreError: 416185:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6895.496048] LustreError: 416186:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6895.499987] LustreError: 416185:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6896.550174] Lustre: Mounted lustre-client [ 6897.071786] LustreError: 416300:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52835be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6897.075554] LustreError: 416300:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6897.152854] Lustre: Unmounted lustre-client [ 6898.096405] Key type lgssc unregistered [ 6898.216463] LNet: 416543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6898.218905] LNetError: 416543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6898.229301] LNet: Removed LNI 192.168.203.4@tcp [ 6898.470122] Key type .llcrypt unregistered [ 6898.471569] Key type ._llcrypt unregistered [ 6898.807072] Key type ._llcrypt registered [ 6898.808329] Key type .llcrypt registered [ 6899.072564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6899.077556] alg: No test for adler32 (adler32-zlib) [ 6899.944600] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6900.036709] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6901.623129] Key type lgssc registered [ 6902.059268] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6906.183506] Lustre: DEBUG MARKER: Iteration 23 [ 6906.312353] LustreError: 417375:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6906.313110] LustreError: 417376:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6906.318098] LustreError: 417375:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6907.381445] Lustre: Mounted lustre-client [ 6907.382519] Lustre: Skipped 1 previous similar message [ 6907.947157] LustreError: 417492:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a1137800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6907.950628] LustreError: 417492:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6908.006791] Lustre: Unmounted lustre-client [ 6909.345324] Key type lgssc unregistered [ 6909.518109] LNet: 417737:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6909.523406] LNetError: 417737:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6909.533997] LNet: Removed LNI 192.168.203.4@tcp [ 6909.855167] Key type .llcrypt unregistered [ 6909.857219] Key type ._llcrypt unregistered [ 6910.277391] Key type ._llcrypt registered [ 6910.278470] Key type .llcrypt registered [ 6910.444515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6910.449536] alg: No test for adler32 (adler32-zlib) [ 6911.321730] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6911.425607] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6913.015103] Key type lgssc registered [ 6913.439902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6917.161125] Lustre: DEBUG MARKER: Iteration 24 [ 6917.277059] LustreError: 418569:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6917.277117] LustreError: 418570:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6917.281625] LustreError: 418569:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6918.355659] Lustre: Mounted lustre-client [ 6918.902647] LustreError: 418684:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528605b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6918.907584] LustreError: 418684:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6918.979415] Lustre: Unmounted lustre-client [ 6919.933986] Key type lgssc unregistered [ 6920.049757] LNet: 418926:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6920.052232] LNetError: 418926:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6920.062193] LNet: Removed LNI 192.168.203.4@tcp [ 6920.316133] Key type .llcrypt unregistered [ 6920.317184] Key type ._llcrypt unregistered [ 6920.647807] Key type ._llcrypt registered [ 6920.649143] Key type .llcrypt registered [ 6920.865347] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6920.870566] alg: No test for adler32 (adler32-zlib) [ 6921.733527] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6921.821358] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6923.407143] Key type lgssc registered [ 6923.801938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6927.538972] Lustre: DEBUG MARKER: Iteration 25 [ 6927.640042] LustreError: 419758:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6927.640097] LustreError: 419759:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6927.644807] LustreError: 419758:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6928.698293] Lustre: Mounted lustre-client [ 6928.699322] Lustre: Skipped 1 previous similar message [ 6929.153419] LustreError: 419870:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5288eea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6929.158497] LustreError: 419870:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6929.213757] Lustre: Unmounted lustre-client [ 6930.223257] Key type lgssc unregistered [ 6930.361617] LNet: 420114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6930.365293] LNetError: 420114:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6930.374060] LNet: Removed LNI 192.168.203.4@tcp [ 6930.698129] Key type .llcrypt unregistered [ 6930.700158] Key type ._llcrypt unregistered [ 6931.007429] Key type ._llcrypt registered [ 6931.008512] Key type .llcrypt registered [ 6931.179046] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6931.183688] alg: No test for adler32 (adler32-zlib) [ 6932.048369] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6932.125421] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6933.711223] Key type lgssc registered [ 6934.160547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6937.615550] Lustre: DEBUG MARKER: Iteration 26 [ 6937.732580] LustreError: 420946:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6937.732582] LustreError: 420947:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6937.737373] LustreError: 420947:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6938.781970] Lustre: Mounted lustre-client [ 6938.783550] Lustre: Skipped 1 previous similar message [ 6939.218850] LustreError: 421053:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5286059800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6939.221945] LustreError: 421053:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6939.267627] Lustre: Unmounted lustre-client [ 6940.196335] Key type lgssc unregistered [ 6940.316522] LNet: 421297:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6940.318879] LNetError: 421297:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6940.325746] LNet: Removed LNI 192.168.203.4@tcp [ 6940.545124] Key type .llcrypt unregistered [ 6940.546241] Key type ._llcrypt unregistered [ 6940.798090] Key type ._llcrypt registered [ 6940.799470] Key type .llcrypt registered [ 6941.000040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6941.006576] alg: No test for adler32 (adler32-zlib) [ 6941.878075] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6941.965987] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6943.559177] Key type lgssc registered [ 6944.001496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6948.023302] Lustre: DEBUG MARKER: Iteration 27 [ 6948.125402] LustreError: 422129:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6948.125642] LustreError: 422130:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6948.129625] LustreError: 422129:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6949.180821] Lustre: Mounted lustre-client [ 6949.182137] Lustre: Skipped 1 previous similar message [ 6949.647207] LustreError: 422242:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a0670000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6949.651591] LustreError: 422242:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6949.683194] Lustre: Unmounted lustre-client [ 6950.624712] Key type lgssc unregistered [ 6950.741540] LNet: 422486:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6950.745509] LNetError: 422486:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6950.753677] LNet: Removed LNI 192.168.203.4@tcp [ 6950.993113] Key type .llcrypt unregistered [ 6950.994214] Key type ._llcrypt unregistered [ 6951.250120] Key type ._llcrypt registered [ 6951.254384] Key type .llcrypt registered [ 6951.432258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6951.445737] alg: No test for adler32 (adler32-zlib) [ 6952.297126] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6952.380542] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6953.967158] Key type lgssc registered [ 6954.363307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6957.841626] Lustre: DEBUG MARKER: Iteration 28 [ 6957.950786] LustreError: 423316:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6957.950837] LustreError: 423317:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6957.955343] LustreError: 423316:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6959.016950] Lustre: Mounted lustre-client [ 6959.574290] LustreError: 423426:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528605e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6959.580223] LustreError: 423426:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6959.648579] Lustre: Unmounted lustre-client [ 6960.615327] Key type lgssc unregistered [ 6960.731353] LNet: 423669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6960.733601] LNetError: 423669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6960.743551] LNet: Removed LNI 192.168.203.4@tcp [ 6960.992112] Key type .llcrypt unregistered [ 6960.993331] Key type ._llcrypt unregistered [ 6961.224574] Key type ._llcrypt registered [ 6961.225775] Key type .llcrypt registered [ 6961.413340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6961.433976] alg: No test for adler32 (adler32-zlib) [ 6962.287962] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6962.369208] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6963.951163] Key type lgssc registered [ 6964.326794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6967.999166] Lustre: DEBUG MARKER: Iteration 29 [ 6968.093262] LustreError: 424500:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6968.093295] LustreError: 424501:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6968.098490] LustreError: 424500:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6969.150097] Lustre: Mounted lustre-client [ 6969.599581] LustreError: 424611:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5289f1c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6969.603717] LustreError: 424611:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6969.648067] Lustre: Unmounted lustre-client [ 6969.649919] Lustre: Skipped 1 previous similar message [ 6970.561262] Key type lgssc unregistered [ 6970.691413] LNet: 424855:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6970.694284] LNetError: 424855:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6970.701720] LNet: Removed LNI 192.168.203.4@tcp [ 6970.917115] Key type .llcrypt unregistered [ 6970.918359] Key type ._llcrypt unregistered [ 6971.169798] Key type ._llcrypt registered [ 6971.171567] Key type .llcrypt registered [ 6971.369605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6971.374441] alg: No test for adler32 (adler32-zlib) [ 6972.228621] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6972.308428] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6973.895146] Key type lgssc registered [ 6974.249762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6977.788074] Lustre: DEBUG MARKER: Iteration 30 [ 6977.888700] LustreError: 425687:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6977.888752] LustreError: 425688:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6977.893273] LustreError: 425687:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6978.951991] Lustre: Mounted lustre-client [ 6978.953182] Lustre: Skipped 1 previous similar message [ 6979.380891] LustreError: 425801:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d529043b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6979.439626] Lustre: Unmounted lustre-client [ 6979.440779] Lustre: Skipped 1 previous similar message [ 6980.360761] Key type lgssc unregistered [ 6980.464469] LNet: 426046:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6980.467758] LNetError: 426046:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6980.476600] LNet: Removed LNI 192.168.203.4@tcp [ 6980.692099] Key type .llcrypt unregistered [ 6980.693709] Key type ._llcrypt unregistered [ 6980.966825] Key type ._llcrypt registered [ 6980.976937] Key type .llcrypt registered [ 6981.135840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6981.140997] alg: No test for adler32 (adler32-zlib) [ 6981.987137] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6982.064687] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6983.639150] Key type lgssc registered [ 6984.027313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6987.791934] Lustre: DEBUG MARKER: Iteration 31 [ 6987.921641] LustreError: 426879:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6987.921695] LustreError: 426880:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6987.928567] LustreError: 426879:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6988.990638] Lustre: Mounted lustre-client [ 6989.455625] LustreError: 426993:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a07b8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6989.459613] LustreError: 426993:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6989.534075] Lustre: Unmounted lustre-client [ 6990.405350] Key type lgssc unregistered [ 6990.512384] LNet: 427236:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6990.514833] LNetError: 427236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6990.522700] LNet: Removed LNI 192.168.203.4@tcp [ 6990.738124] Key type .llcrypt unregistered [ 6990.739970] Key type ._llcrypt unregistered [ 6991.027609] Key type ._llcrypt registered [ 6991.048317] Key type .llcrypt registered [ 6991.225857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6991.230831] alg: No test for adler32 (adler32-zlib) [ 6992.085521] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 6992.171178] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 6993.759122] Key type lgssc registered [ 6994.146778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6997.804268] Lustre: DEBUG MARKER: Iteration 32 [ 6997.899486] LustreError: 428067:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6997.899534] LustreError: 428068:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6997.904502] LustreError: 428067:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6998.963972] Lustre: Mounted lustre-client [ 6999.521306] LustreError: 428180:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5291623000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6999.527943] LustreError: 428180:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6999.560867] Lustre: Unmounted lustre-client [ 7000.551480] Key type lgssc unregistered [ 7000.670500] LNet: 428424:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7000.673158] LNetError: 428424:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7000.681738] LNet: Removed LNI 192.168.203.4@tcp [ 7000.911123] Key type .llcrypt unregistered [ 7000.912986] Key type ._llcrypt unregistered [ 7001.177685] Key type ._llcrypt registered [ 7001.178907] Key type .llcrypt registered [ 7001.411099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7001.416768] alg: No test for adler32 (adler32-zlib) [ 7002.283453] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7002.376230] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7003.967161] Key type lgssc registered [ 7004.349662] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7007.874929] Lustre: DEBUG MARKER: Iteration 33 [ 7007.987995] LustreError: 429256:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7007.988088] LustreError: 429257:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7007.993582] LustreError: 429256:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7009.051636] Lustre: Mounted lustre-client [ 7009.053456] Lustre: Skipped 1 previous similar message [ 7009.515714] LustreError: 429375:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528386a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7009.519333] LustreError: 429375:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7009.604193] Lustre: Unmounted lustre-client [ 7010.623736] Key type lgssc unregistered [ 7010.751718] LNet: 429619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7010.754209] LNetError: 429619:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7010.764325] LNet: Removed LNI 192.168.203.4@tcp [ 7011.024103] Key type .llcrypt unregistered [ 7011.025202] Key type ._llcrypt unregistered [ 7011.319172] Key type ._llcrypt registered [ 7011.320338] Key type .llcrypt registered [ 7011.560260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7011.565876] alg: No test for adler32 (adler32-zlib) [ 7012.450334] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7012.550258] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7014.135189] Key type lgssc registered [ 7014.554315] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7018.313593] Lustre: DEBUG MARKER: Iteration 34 [ 7018.437081] LustreError: 430450:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7018.438374] LustreError: 430451:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7018.443093] LustreError: 430450:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7019.506532] Lustre: Mounted lustre-client [ 7019.508694] Lustre: Skipped 1 previous similar message [ 7020.047347] LustreError: 430567:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5285649000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7020.050094] LustreError: 430567:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7020.115143] Lustre: Unmounted lustre-client [ 7021.148085] Key type lgssc unregistered [ 7021.269448] LNet: 430810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7021.271811] LNetError: 430810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7021.279581] LNet: Removed LNI 192.168.203.4@tcp [ 7021.515109] Key type .llcrypt unregistered [ 7021.516682] Key type ._llcrypt unregistered [ 7021.819939] Key type ._llcrypt registered [ 7021.821140] Key type .llcrypt registered [ 7021.995310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7022.000355] alg: No test for adler32 (adler32-zlib) [ 7022.853924] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7022.931221] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7024.519120] Key type lgssc registered [ 7024.895083] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7028.443684] Lustre: DEBUG MARKER: Iteration 35 [ 7028.544808] LustreError: 431641:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7028.544863] LustreError: 431642:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7028.550991] LustreError: 431641:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7029.607279] Lustre: Mounted lustre-client [ 7029.608363] Lustre: Skipped 1 previous similar message [ 7030.102444] LustreError: 431757:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52b23ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7030.159767] Lustre: Unmounted lustre-client [ 7031.054162] Key type lgssc unregistered [ 7031.164440] LNet: 431999:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7031.166892] LNetError: 431999:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7031.177453] LNet: Removed LNI 192.168.203.4@tcp [ 7031.390088] Key type .llcrypt unregistered [ 7031.391462] Key type ._llcrypt unregistered [ 7031.626864] Key type ._llcrypt registered [ 7031.627926] Key type .llcrypt registered [ 7031.834814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7031.841419] alg: No test for adler32 (adler32-zlib) [ 7032.703913] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7032.797960] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7034.383125] Key type lgssc registered [ 7034.813995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7038.610553] Lustre: DEBUG MARKER: Iteration 36 [ 7038.718919] LustreError: 432831:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7038.718948] LustreError: 432832:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7038.723913] LustreError: 432831:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7039.778409] Lustre: Mounted lustre-client [ 7040.230243] LustreError: 432939:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d529aa5a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7040.234306] LustreError: 432939:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7040.295743] Lustre: Unmounted lustre-client [ 7041.237884] Key type lgssc unregistered [ 7041.355537] LNet: 433184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7041.358373] LNetError: 433184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7041.367653] LNet: Removed LNI 192.168.203.4@tcp [ 7041.616097] Key type .llcrypt unregistered [ 7041.617264] Key type ._llcrypt unregistered [ 7041.901405] Key type ._llcrypt registered [ 7041.916944] Key type .llcrypt registered [ 7042.087958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7042.093220] alg: No test for adler32 (adler32-zlib) [ 7042.962389] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7043.051529] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7044.639161] Key type lgssc registered [ 7045.053934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7048.622355] Lustre: DEBUG MARKER: Iteration 37 [ 7048.722763] LustreError: 434016:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7048.722820] LustreError: 434017:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7048.728239] LustreError: 434016:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7049.774457] Lustre: Mounted lustre-client [ 7049.776147] Lustre: Skipped 1 previous similar message [ 7050.217160] LustreError: 434127:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528514f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7050.221695] LustreError: 434127:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7050.278170] Lustre: Unmounted lustre-client [ 7051.187300] Key type lgssc unregistered [ 7051.305559] LNet: 434371:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7051.307928] LNetError: 434371:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7051.315666] LNet: Removed LNI 192.168.203.4@tcp [ 7051.542097] Key type .llcrypt unregistered [ 7051.543233] Key type ._llcrypt unregistered [ 7051.813643] Key type ._llcrypt registered [ 7051.815931] Key type .llcrypt registered [ 7052.020815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7052.027080] alg: No test for adler32 (adler32-zlib) [ 7052.884476] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7052.969546] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7054.551140] Key type lgssc registered [ 7054.953464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7058.820333] Lustre: DEBUG MARKER: Iteration 38 [ 7058.935888] LustreError: 435202:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7058.936228] LustreError: 435203:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7058.940771] LustreError: 435202:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7060.002770] Lustre: Mounted lustre-client [ 7060.519401] LustreError: 435313:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52ae92b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7060.522960] LustreError: 435313:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7060.596948] Lustre: Unmounted lustre-client [ 7061.661699] Key type lgssc unregistered [ 7061.780774] LNet: 435557:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7061.784067] LNetError: 435557:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7061.792649] LNet: Removed LNI 192.168.203.4@tcp [ 7062.060125] Key type .llcrypt unregistered [ 7062.061429] Key type ._llcrypt unregistered [ 7062.335282] Key type ._llcrypt registered [ 7062.336427] Key type .llcrypt registered [ 7062.624404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7062.630150] alg: No test for adler32 (adler32-zlib) [ 7063.495648] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7063.590872] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7065.175214] Key type lgssc registered [ 7065.564259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7069.740418] Lustre: DEBUG MARKER: Iteration 39 [ 7069.861355] LustreError: 436389:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7069.863566] LustreError: 436390:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7069.865600] LustreError: 436389:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7070.918357] Lustre: Mounted lustre-client [ 7071.392886] LustreError: 436507:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52bfefa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7071.505083] Lustre: Unmounted lustre-client [ 7072.457434] Key type lgssc unregistered [ 7072.561585] LNet: 436750:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7072.564487] LNetError: 436750:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7072.574828] LNet: Removed LNI 192.168.203.4@tcp [ 7072.809126] Key type .llcrypt unregistered [ 7072.810197] Key type ._llcrypt unregistered [ 7073.055721] Key type ._llcrypt registered [ 7073.057165] Key type .llcrypt registered [ 7073.227154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7073.234739] alg: No test for adler32 (adler32-zlib) [ 7074.086856] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7074.172114] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7075.759199] Key type lgssc registered [ 7076.168885] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7079.784520] Lustre: DEBUG MARKER: Iteration 40 [ 7079.904198] LustreError: 437581:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7079.904230] LustreError: 437582:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7079.908440] LustreError: 437581:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7080.971990] Lustre: Mounted lustre-client [ 7080.973356] Lustre: Skipped 1 previous similar message [ 7081.396502] LustreError: 437690:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a9690000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7081.470134] Lustre: Unmounted lustre-client [ 7082.538152] Key type lgssc unregistered [ 7082.673814] LNet: 437932:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7082.678421] LNetError: 437932:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7082.689919] LNet: Removed LNI 192.168.203.4@tcp [ 7082.966179] Key type .llcrypt unregistered [ 7082.968611] Key type ._llcrypt unregistered [ 7083.235952] Key type ._llcrypt registered [ 7083.237371] Key type .llcrypt registered [ 7083.414343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7083.418756] alg: No test for adler32 (adler32-zlib) [ 7084.274581] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7084.359752] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7085.943173] Key type lgssc registered [ 7086.308407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7089.675400] Lustre: DEBUG MARKER: Iteration 41 [ 7089.775546] LustreError: 438763:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7089.775581] LustreError: 438764:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7089.780708] LustreError: 438763:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7090.836463] Lustre: Mounted lustre-client [ 7091.270645] LustreError: 438878:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d528389b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7091.332797] Lustre: Unmounted lustre-client [ 7092.395324] Key type lgssc unregistered [ 7092.507390] LNet: 439122:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7092.509587] LNetError: 439122:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7092.518652] LNet: Removed LNI 192.168.203.4@tcp [ 7092.761119] Key type .llcrypt unregistered [ 7092.762644] Key type ._llcrypt unregistered [ 7093.013847] Key type ._llcrypt registered [ 7093.015132] Key type .llcrypt registered [ 7093.185884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7093.196845] alg: No test for adler32 (adler32-zlib) [ 7094.064447] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7094.150700] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7095.735199] Key type lgssc registered [ 7096.182055] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7099.755971] Lustre: DEBUG MARKER: Iteration 42 [ 7099.854346] LustreError: 439952:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7099.854769] LustreError: 439953:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7099.858771] LustreError: 439952:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7100.913229] Lustre: Mounted lustre-client [ 7100.915689] Lustre: Skipped 1 previous similar message [ 7101.342036] LustreError: 440059:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52887b8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7101.346215] LustreError: 440059:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7101.414616] Lustre: Unmounted lustre-client [ 7102.456761] Key type lgssc unregistered [ 7102.586529] LNet: 440303:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7102.590245] LNetError: 440303:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7102.603739] LNet: Removed LNI 192.168.203.4@tcp [ 7102.841094] Key type .llcrypt unregistered [ 7102.842201] Key type ._llcrypt unregistered [ 7103.061212] Key type ._llcrypt registered [ 7103.062324] Key type .llcrypt registered [ 7103.276175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7103.281041] alg: No test for adler32 (adler32-zlib) [ 7104.138605] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7104.223773] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7105.807104] Key type lgssc registered [ 7106.188314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7109.745274] Lustre: DEBUG MARKER: Iteration 43 [ 7109.865976] LustreError: 441134:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7109.867177] LustreError: 441135:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7109.871981] LustreError: 441134:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7110.935265] Lustre: Mounted lustre-client [ 7111.476092] LustreError: 441248:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5291622800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7111.480819] LustreError: 441248:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7111.542983] Lustre: Unmounted lustre-client [ 7112.613855] Key type lgssc unregistered [ 7112.728600] LNet: 441492:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7112.731338] LNetError: 441492:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7112.738485] LNet: Removed LNI 192.168.203.4@tcp [ 7113.007170] Key type .llcrypt unregistered [ 7113.008204] Key type ._llcrypt unregistered [ 7113.284974] Key type ._llcrypt registered [ 7113.289065] Key type .llcrypt registered [ 7113.606545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7113.611392] alg: No test for adler32 (adler32-zlib) [ 7114.481106] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7114.568053] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7116.159142] Key type lgssc registered [ 7116.549312] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7120.309373] Lustre: DEBUG MARKER: Iteration 44 [ 7120.416690] LustreError: 442323:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7120.417133] LustreError: 442324:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7120.421411] LustreError: 442323:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7121.472432] Lustre: Mounted lustre-client [ 7121.901294] LustreError: 442435:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5291627800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7121.904357] LustreError: 442435:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7121.999117] Lustre: Unmounted lustre-client [ 7122.000188] Lustre: Skipped 1 previous similar message [ 7122.943486] Key type lgssc unregistered [ 7123.058792] LNet: 442678:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7123.062366] LNetError: 442678:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7123.079521] LNet: Removed LNI 192.168.203.4@tcp [ 7123.403165] Key type .llcrypt unregistered [ 7123.405111] Key type ._llcrypt unregistered [ 7123.664208] Key type ._llcrypt registered [ 7123.667169] Key type .llcrypt registered [ 7123.849272] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7123.853894] alg: No test for adler32 (adler32-zlib) [ 7124.710668] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7124.794694] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7126.375167] Key type lgssc registered [ 7126.803240] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7130.477368] Lustre: DEBUG MARKER: Iteration 45 [ 7130.664092] LustreError: 443510:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7130.664464] LustreError: 443509:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7130.668181] LustreError: 443510:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7131.734256] Lustre: Mounted lustre-client [ 7132.196309] LustreError: 443625:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d529aa5f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7132.199383] LustreError: 443625:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7132.254439] Lustre: Unmounted lustre-client [ 7133.635958] Key type lgssc unregistered [ 7133.804743] LNet: 443866:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7133.809932] LNetError: 443866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7133.821610] LNet: Removed LNI 192.168.203.4@tcp [ 7134.181130] Key type .llcrypt unregistered [ 7134.184025] Key type ._llcrypt unregistered [ 7134.535275] Key type ._llcrypt registered [ 7134.536677] Key type .llcrypt registered [ 7134.774345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7134.779839] alg: No test for adler32 (adler32-zlib) [ 7135.648809] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7135.748846] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7137.335164] Key type lgssc registered [ 7137.729717] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7141.690475] Lustre: DEBUG MARKER: Iteration 46 [ 7141.790303] LustreError: 444697:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7141.790334] LustreError: 444699:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7141.796421] LustreError: 444697:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7142.852609] Lustre: Mounted lustre-client [ 7143.294845] LustreError: 444812:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52a0674800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7143.298988] LustreError: 444812:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7143.328351] Lustre: Unmounted lustre-client [ 7144.276705] Key type lgssc unregistered [ 7144.397499] LNet: 445055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7144.401159] LNetError: 445055:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7144.410706] LNet: Removed LNI 192.168.203.4@tcp [ 7144.644094] Key type .llcrypt unregistered [ 7144.645506] Key type ._llcrypt unregistered [ 7144.935601] Key type ._llcrypt registered [ 7144.936773] Key type .llcrypt registered [ 7145.115671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7145.120895] alg: No test for adler32 (adler32-zlib) [ 7145.983932] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7146.071675] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7147.655127] Key type lgssc registered [ 7148.011961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7151.960935] Lustre: DEBUG MARKER: Iteration 47 [ 7152.084213] LustreError: 445887:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7152.084267] LustreError: 445888:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7152.090264] LustreError: 445887:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7153.142265] Lustre: Mounted lustre-client [ 7153.143382] Lustre: Skipped 1 previous similar message [ 7153.571583] LustreError: 446000:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52aa69f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7153.576223] LustreError: 446000:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7153.627093] Lustre: Unmounted lustre-client [ 7154.589832] Key type lgssc unregistered [ 7154.700515] LNet: 446243:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7154.704505] LNetError: 446243:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7154.711721] LNet: Removed LNI 192.168.203.4@tcp [ 7154.953148] Key type .llcrypt unregistered [ 7154.954939] Key type ._llcrypt unregistered [ 7155.227570] Key type ._llcrypt registered [ 7155.228895] Key type .llcrypt registered [ 7155.404034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7155.408711] alg: No test for adler32 (adler32-zlib) [ 7156.264866] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7156.346885] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7157.927158] Key type lgssc registered [ 7158.306947] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7161.864552] Lustre: DEBUG MARKER: Iteration 48 [ 7161.979343] LustreError: 447076:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7161.980385] LustreError: 447077:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7161.985478] LustreError: 447076:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7163.054031] Lustre: Mounted lustre-client [ 7163.652972] LustreError: 447194:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52aa69f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7163.658229] LustreError: 447194:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7163.703742] Lustre: Unmounted lustre-client [ 7164.777475] Key type lgssc unregistered [ 7164.888480] LNet: 447436:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7164.890982] LNetError: 447436:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7164.899659] LNet: Removed LNI 192.168.203.4@tcp [ 7165.134136] Key type .llcrypt unregistered [ 7165.135146] Key type ._llcrypt unregistered [ 7165.446514] Key type ._llcrypt registered [ 7165.447533] Key type .llcrypt registered [ 7165.678148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7165.683755] alg: No test for adler32 (adler32-zlib) [ 7166.550314] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7166.647471] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7168.231140] Key type lgssc registered [ 7168.661161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7172.121889] Lustre: DEBUG MARKER: Iteration 49 [ 7172.232899] LustreError: 448268:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7172.233940] LustreError: 448269:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7172.238064] LustreError: 448268:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7173.296388] Lustre: Mounted lustre-client [ 7173.808933] LustreError: 448379:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5284de0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7173.812776] LustreError: 448379:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7173.881615] Lustre: Unmounted lustre-client [ 7173.882760] Lustre: Skipped 1 previous similar message [ 7174.797213] Key type lgssc unregistered [ 7174.912527] LNet: 448623:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7174.914898] LNetError: 448623:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7174.923695] LNet: Removed LNI 192.168.203.4@tcp [ 7175.158105] Key type .llcrypt unregistered [ 7175.159133] Key type ._llcrypt unregistered [ 7175.432975] Key type ._llcrypt registered [ 7175.434157] Key type .llcrypt registered [ 7175.605556] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7175.610361] alg: No test for adler32 (adler32-zlib) [ 7176.466663] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7176.548187] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7178.127125] Key type lgssc registered [ 7178.478585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7182.090355] Lustre: DEBUG MARKER: Iteration 50 [ 7182.203457] LustreError: 449454:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 7182.203501] LustreError: 449456:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 7182.208240] LustreError: 449454:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7183.254018] Lustre: Mounted lustre-client [ 7183.255628] Lustre: Skipped 1 previous similar message [ 7183.690416] LustreError: 449564:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d5299328800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7183.694191] LustreError: 449564:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 7183.766559] Lustre: Unmounted lustre-client [ 7184.689689] Key type lgssc unregistered [ 7184.793549] LNet: 449808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7184.796470] LNetError: 449808:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7184.805775] LNet: Removed LNI 192.168.203.4@tcp [ 7185.055093] Key type .llcrypt unregistered [ 7185.056176] Key type ._llcrypt unregistered [ 7185.299537] Key type ._llcrypt registered [ 7185.301172] Key type .llcrypt registered [ 7185.503882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7185.509380] alg: No test for adler32 (adler32-zlib) [ 7186.359806] Lustre: Lustre: Build Version: 2.17.51_74_g68f2299 [ 7186.439272] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 7188.023080] Key type lgssc registered [ 7188.400731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7192.687439] Lustre: Mounted lustre-client [ 7194.949500] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 02:39:01 (1776926341) [ 7211.487145] Lustre: 451164:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926342/real 1776926342] req@ffff9d5298d58380 x1863242306561920/t0(0) o36->lustre-MDT0000-mdc-ffff9d52aa69a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1776926358 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7211.500190] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7211.511140] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7227.871213] Lustre: 451164:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926358/real 1776926358] req@ffff9d5298d58380 x1863242306561920/t0(0) o36->lustre-MDT0000-mdc-ffff9d52aa69a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1776926374 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7227.879794] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7227.892552] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7243.231153] Lustre: 451164:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926374/real 1776926374] req@ffff9d5298d58380 x1863242306561920/t0(0) o36->lustre-MDT0000-mdc-ffff9d52aa69a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1776926390 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7243.239974] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7243.248825] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7259.615127] Lustre: 451164:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926390/real 1776926390] req@ffff9d5298d58380 x1863242306561920/t0(0) o36->lustre-MDT0000-mdc-ffff9d52aa69a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1776926406 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7259.622535] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7259.631469] Lustre: lustre-MDT0000-mdc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7260.115118] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 02:40:06 (1776926406) [ 7265.842163] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 02:40:12 (1776926412) [ 7269.043876] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 02:40:15 (1776926415) [ 7271.177596] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 02:40:17 (1776926417) [ 7278.007651] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 02:40:24 (1776926424) [ 7290.185852] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 02:40:36 (1776926436) [ 7292.432000] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 02:40:38 (1776926438) [ 7292.506665] LustreError: 455936:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7294.591080] LustreError: 455936:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7296.665498] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 02:40:43 (1776926443) [ 7314.847125] Lustre: 450000:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926445/real 1776926445] req@ffff9d52aeec0a80 x1863242307596928/t0(0) o4->lustre-OST0000-osc-ffff9d52aa69a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1776926461 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7314.847182] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7314.855111] Lustre: 450000:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 7314.864108] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7330.271186] Lustre: 450000:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926461/real 1776926461] req@ffff9d52aeec0a80 x1863242307596928/t0(0) o4->lustre-OST0000-osc-ffff9d52aa69a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1776926477 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7330.271243] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7330.278383] Lustre: 450000:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 7330.288554] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7346.463136] Lustre: 450003:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776926477/real 1776926477] req@ffff9d5283f41880 x1863242307595776/t0(0) o4->lustre-OST0000-osc-ffff9d52aa69a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1776926493 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7346.470446] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7346.479029] Lustre: lustre-OST0000-osc-ffff9d52aa69a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 7386.187873] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d52aa698000.ost_server_uuid 50 [ 7386.660608] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d52aa698000.ost_server_uuid in IDLE state after 0 sec [ 7387.170796] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7387.739461] Lustre: DEBUG MARKER: == sanityn test complete, duration 7182 sec ============== 02:42:14 (1776926534) [ 7388.255783] Lustre: DEBUG MARKER: === sanityn: start cleanup 02:42:14 (1776926534) === [ 7447.719349] LustreError: 457984:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52aa698000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7447.740147] Lustre: Unmounted lustre-client [ 7448.916582] Lustre: DEBUG MARKER: === sanityn: finish cleanup 02:43:15 (1776926595) === [ 7449.255493] LustreError: 458287:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d52aa69a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7449.258627] LustreError: 458287:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 7449.285550] Lustre: Unmounted lustre-client [ 7487.670172] Key type lgssc unregistered [ 7487.782379] LNet: 458970:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7487.784911] LNetError: 458970:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7487.792826] LNet: Removed LNI 192.168.203.4@tcp [ 7488.028141] Key type .llcrypt unregistered [ 7488.029286] Key type ._llcrypt unregistered