[ 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 453086076 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003737] x2apic enabled [ 0.004018] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.008331] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010000] pid_max: default: 32768 minimum: 301 [ 0.010145] LSM: Security Framework initializing [ 0.011055] Yama: becoming mindful. [ 0.012043] SELinux: Initializing. [ 0.013079] *** VALIDATE selinux *** [ 0.022727] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027820] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028196] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029133] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030151] *** VALIDATE tmpfs *** [ 0.031596] *** VALIDATE proc *** [ 0.032252] *** VALIDATE cgroup *** [ 0.033007] *** VALIDATE cgroup2 *** [ 0.035223] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037090] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039026] Spectre V2 : User space: Vulnerable [ 0.040007] Speculative Store Bypass: Vulnerable [ 0.044096] debug: unmapping init [mem 0xffffffff9c059000-0xffffffff9c060fff] [ 0.047000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048270] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049032] ... version: 2 [ 0.050010] ... bit width: 48 [ 0.051008] ... generic registers: 4 [ 0.052008] ... value mask: 0000ffffffffffff [ 0.053009] ... max period: 00007fffffffffff [ 0.054017] ... fixed-purpose events: 3 [ 0.055011] ... event mask: 000000070000000f [ 0.056363] rcu: Hierarchical SRCU implementation. [ 0.058854] smp: Bringing up secondary CPUs ... [ 0.059760] x86: Booting SMP configuration: [ 0.060026] .... node #0, CPUs: #1 #2 #3 [ 0.073318] smp: Brought up 1 node, 4 CPUs [ 0.075031] smpboot: Max logical packages: 1 [ 0.076026] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.121632] node 0 deferred pages initialised in 43ms [ 0.132454] devtmpfs: initialized [ 0.133293] x86/mm: Memory block size: 128MB [ 0.136225] gcov: version magic: 0x41383552 [ 0.140322] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.144079] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.147337] pinctrl core: initialized pinctrl subsystem [ 0.149222] [ 0.149840] ************************************************************* [ 0.153015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.155011] ** ** [ 0.157009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.159015] ** ** [ 0.161049] ** This means that this kernel is built to expose internal ** [ 0.163010] ** IOMMU data structures, which may compromise security on ** [ 0.165056] ** your system. ** [ 0.167011] ** ** [ 0.168000] ** If you see this message and you are not debugging the ** [ 0.170011] ** kernel, report this immediately to your vendor! ** [ 0.172010] ** ** [ 0.173021] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.175011] ************************************************************* [ 0.176563] NET: Registered protocol family 16 [ 0.177623] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.181051] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.184069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.188092] cpuidle: using governor menu [ 0.191008] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.193380] PCI: Using configuration type 1 for base access [ 0.195175] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.204084] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.205020] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.207024] cryptd: max_cpu_qlen set to 1000 [ 0.209589] ACPI: Added _OSI(Module Device) [ 0.210013] ACPI: Added _OSI(Processor Device) [ 0.212009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.214010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.223179] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.233429] ACPI: Interpreter enabled [ 0.235149] ACPI: PM: (supports S0 S3 S4 S5) [ 0.237008] ACPI: Using IOAPIC for interrupt routing [ 0.240423] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.246559] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.266090] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.270052] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.275016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.282447] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.290497] acpiphp: Slot [2] registered [ 0.292438] acpiphp: Slot [5] registered [ 0.296148] acpiphp: Slot [6] registered [ 0.297081] acpiphp: Slot [3] registered [ 0.299227] acpiphp: Slot [4] registered [ 0.301159] acpiphp: Slot [7] registered [ 0.303168] acpiphp: Slot [8] registered [ 0.306093] acpiphp: Slot [9] registered [ 0.308144] acpiphp: Slot [10] registered [ 0.309088] acpiphp: Slot [11] registered [ 0.311072] acpiphp: Slot [12] registered [ 0.312069] acpiphp: Slot [13] registered [ 0.314183] acpiphp: Slot [14] registered [ 0.316206] acpiphp: Slot [15] registered [ 0.318123] acpiphp: Slot [16] registered [ 0.320113] acpiphp: Slot [17] registered [ 0.322206] acpiphp: Slot [18] registered [ 0.324104] acpiphp: Slot [19] registered [ 0.326204] acpiphp: Slot [20] registered [ 0.329118] acpiphp: Slot [21] registered [ 0.331111] acpiphp: Slot [22] registered [ 0.332140] acpiphp: Slot [23] registered [ 0.334126] acpiphp: Slot [24] registered [ 0.336086] acpiphp: Slot [25] registered [ 0.338081] acpiphp: Slot [26] registered [ 0.340574] acpiphp: Slot [27] registered [ 0.343134] acpiphp: Slot [28] registered [ 0.345195] acpiphp: Slot [29] registered [ 0.347082] acpiphp: Slot [30] registered [ 0.349119] acpiphp: Slot [31] registered [ 0.350096] PCI host bridge to bus 0000:00 [ 0.352018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.355022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.358126] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.361024] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.364071] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.368052] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.371419] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.374229] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.379321] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.386804] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.390024] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.392017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.397037] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.400020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.403071] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.405757] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.408037] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.411999] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.418015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.430021] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.437092] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.448548] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.456037] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.468928] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.494034] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.515545] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.525024] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.536019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.552032] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.570510] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.572493] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.575381] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.577548] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.580205] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.585091] iommu: Default domain type: Passthrough [ 0.587434] SCSI subsystem initialized [ 0.588179] ACPI: bus type USB registered [ 0.590122] usbcore: registered new interface driver usbfs [ 0.592073] usbcore: registered new interface driver hub [ 0.594083] usbcore: registered new device driver usb [ 0.596310] pps_core: LinuxPPS API ver. 1 registered [ 0.598012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.601087] PTP clock support registered [ 0.603291] EDAC MC: Ver: 3.0.0 [ 0.605114] PCI: Using ACPI for IRQ routing [ 0.607045] NetLabel: Initializing [ 0.609014] NetLabel: domain hash size = 128 [ 0.611010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.613098] NetLabel: unlabeled traffic allowed by default [ 0.615440] vgaarb: loaded [ 0.617564] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.620012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.629171] clocksource: Switched to clocksource kvm-clock [ 0.811578] VFS: Disk quotas dquot_6.6.0 [ 0.813835] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.816919] *** VALIDATE ramfs *** [ 0.819062] *** VALIDATE hugetlbfs *** [ 0.822920] pnp: PnP ACPI init [ 0.828179] pnp: PnP ACPI: found 6 devices [ 0.844925] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.849477] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.852350] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.855159] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.858796] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.862027] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.865985] NET: Registered protocol family 2 [ 0.869676] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.877390] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.882644] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.890925] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.896833] TCP: Hash tables configured (established 65536 bind 65536) [ 0.901460] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.906477] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.911787] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.917778] NET: Registered protocol family 1 [ 0.921922] RPC: Registered named UNIX socket transport module. [ 0.924255] RPC: Registered udp transport module. [ 0.926139] RPC: Registered tcp transport module. [ 0.927953] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.930554] NET: Registered protocol family 44 [ 0.932683] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.935203] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.937938] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.940596] PCI: CLS 0 bytes, default 64 [ 0.943335] Unpacking initramfs... [ 2.960763] debug: unmapping init [mem 0xffff8ce17cc64000-0xffff8ce17ffcffff] [ 2.976679] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.985993] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.992878] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.980671] Initialise system trusted keyrings [ 4.990828] Key type blacklist registered [ 5.001974] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 5.172782] zbud: loaded [ 5.234161] *** VALIDATE nfs *** [ 5.301063] *** VALIDATE nfs4 *** [ 5.302709] pstore: using deflate compression [ 5.307890] Platform Keyring initialized [ 5.437897] NET: Registered protocol family 38 [ 5.439879] Key type asymmetric registered [ 5.441273] Asymmetric key parser 'x509' registered [ 5.443598] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 5.446788] io scheduler mq-deadline registered [ 5.448598] io scheduler kyber registered [ 5.450521] io scheduler bfq registered [ 5.452552] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 5.455599] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 5.458938] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.462164] ACPI: Power Button [PWRF] [ 5.482568] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 5.501219] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 5.527651] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.564161] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.593568] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.598696] Non-volatile memory driver v1.3 [ 5.600376] Linux agpgart interface v0.103 [ 5.632488] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 5.635812] vda: detected capacity change from 0 to 68579328 [ 5.723444] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.728251] vdb: detected capacity change from 0 to 1073741824 [ 5.799449] libphy: Fixed MDIO Bus: probed [ 5.809513] usbcore: registered new interface driver usbserial_generic [ 5.813484] usbserial: USB Serial support registered for generic [ 5.819221] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.830070] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.834688] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.838939] mousedev: PS/2 mouse device common for all mice [ 5.844371] rtc_cmos 00:05: RTC can wake from S4 [ 5.850172] rtc_cmos 00:05: registered as rtc0 [ 5.853504] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.861069] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.861451] intel_pstate: CPU model not supported [ 5.885611] hid: raw HID events driver (C) Jiri Kosina [ 5.885819] usbcore: registered new interface driver usbhid [ 5.885823] usbhid: USB HID core driver [ 5.886240] drop_monitor: Initializing network drop monitor service [ 5.886395] Initializing XFRM netlink socket [ 5.886826] NET: Registered protocol family 10 [ 5.900617] Segment Routing with IPv6 [ 6.001596] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 6.005543] NET: Registered protocol family 17 [ 6.013087] mpls_gso: MPLS GSO support [ 6.038489] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 6.055554] RAS: Correctable Errors collector initialized. [ 6.060072] AVX version of gcm_enc/dec engaged. [ 6.065471] AES CTR mode by8 optimization enabled [ 6.256518] sched_clock: Marking stable (6256487830, 0)->(7376258184, -1119770354) [ 6.267584] registered taskstats version 1 [ 6.270234] Loading compiled-in X.509 certificates [ 6.272899] zswap: loaded using pool lzo/zbud [ 6.338827] Key type big_key registered [ 6.359505] Key type encrypted registered [ 6.361622] ima: No TPM chip found, activating TPM-bypass! [ 6.364905] ima: Allocated hash algorithm: sha1 [ 6.368398] ima: No architecture policies found [ 6.371622] evm: Initialising EVM extended attributes: [ 6.373899] evm: security.selinux [ 6.375541] evm: security.ima [ 6.376714] evm: security.capability [ 6.378554] evm: HMAC attrs: 0x1 [ 6.381711] rtc_cmos 00:05: setting system clock to 2026-04-10 14:38:45 UTC (1775831925) [ 6.399578] debug: unmapping init [mem 0xffffffff9d003000-0xffffffff9d1fffff] [ 6.405736] debug: unmapping init [mem 0xffffffff9bd82000-0xffffffff9c058fff] [ 6.413205] Write protecting the kernel read-only data: 28672k [ 6.419520] debug: unmapping init [mem 0xffffffff9a403000-0xffffffff9a5fffff] [ 6.424451] debug: unmapping init [mem 0xffffffff9ad14000-0xffffffff9adfffff] [ 6.501333] 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) [ 6.513849] systemd[1]: Detected virtualization kvm. [ 6.515694] systemd[1]: Detected architecture x86-64. [ 6.519437] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.550470] systemd[1]: No hostname configured. [ 6.553834] systemd[1]: Set hostname to . [ 6.557161] random: systemd: uninitialized urandom read (16 bytes read) [ 6.561408] systemd[1]: Initializing machine ID from random generator. [ 6.709271] random: ln: uninitialized urandom read (6 bytes read) [ 7.207837] random: systemd: uninitialized urandom read (16 bytes read) [ 7.212307] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 7.237878] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 7.246204] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Swap. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... Starting Setup Virtual Console... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.924391] device-mapper: uevent: version 1.0.3 [ 8.929436] 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. [ 10.547356] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 10.728412] scsi host0: ata_piix [ 10.794054] random: fast init done [ 10.865800] scsi host1: ata_piix [ 10.868693] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.873619] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.922359] random: crng init done [ 15.926749] random: 7 urandom warning(s) missed due to ratelimiting [ 18.561745] dracut-initqueue[592]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 20.946932] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 25.194855] printk: systemd: 26 output lines suppressed due to ratelimiting [ 26.630141] SELinux: Disabled at runtime. [ 26.775820] 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) [ 26.791420] systemd[1]: Detected virtualization kvm. [ 26.793712] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 28.862436] systemd[1]: initrd-switch-root.service: Succeeded. [ 28.875601] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 28.898281] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 28.928217] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 28.942088] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 28.973446] systemd[1]: Starting Journal Service... Starting Journal Service... [ 29.066564] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. [ 29.176353] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Mounted /mnt. [ 31.736895] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 33.090869] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 33.645931] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit)[ 38.939403] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 38.971405] EDAC sbridge: Ver: 1.1.2 [*** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (11s / no limit) [ ***] A start job is running for Configur…only root support (12s / no limit) [ **] A start job is running for Configur…only root support (12s / no limit) [ *] A start job is running for Configur…only root support (13s / no limit)[ 42.212857] Key type dns_resolver registered [ **] A start job is running for Configur…only root support (14s / no limit) [ ***] A start job is running for Configur…only root support (14s / no limit)[ 43.393463] NFS: Registering the id_resolver key type [ 43.395912] Key type id_resolver registered [ 43.397631] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (15s / no limit) [ *** ] A start job is running for Configur…only root support (15s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ 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 ] 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 Daily Cleanup of Temporary Directories. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. 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 Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg447-client login: [ 112.658228] libcfs: loading out-of-tree module taints kernel. [ 112.700660] Key type ._llcrypt registered [ 112.702815] Key type .llcrypt registered [ 113.149902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 113.157681] alg: No test for adler32 (adler32-zlib) [ 114.409876] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 114.975610] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 116.640166] Key type lgssc registered [ 117.701689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 205.922379] Lustre: Mounted lustre-client [ 209.899933] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 222.959503] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing check_logdir /tmp/testlogs/ [ 226.670989] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing yml_node [ 229.556624] Lustre: DEBUG MARKER: Client: 2.17.51.75 [ 231.397573] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: disconnect after 24s idle [ 231.671611] Lustre: DEBUG MARKER: MDS: 2.17.51.75 [ 233.583301] Lustre: DEBUG MARKER: OSS: 2.17.51.75 [ 234.598195] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Apr 10 10:42:32 EDT 2026 [ 246.405920] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 247.459739] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 248.518142] Lustre: DEBUG MARKER: === sanityn: start setup 10:42:46 (1775832166) === [ 248.937484] Lustre: Mounted lustre-client [ 251.232701] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing check_config_client /mnt/lustre [ 266.062345] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 269.795882] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: disconnect after 20s idle [ 269.799034] Lustre: Skipped 1 previous similar message [ 275.429093] Lustre: DEBUG MARKER: === sanityn: finish setup 10:43:13 (1775832193) === [ 277.230679] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 10:43:15 (1775832195) [ 282.757204] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 10:43:21 (1775832201) [ 288.283486] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 10:43:26 (1775832206) [ 292.549613] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 10:43:30 (1775832210) [ 296.894520] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 10:43:35 (1775832215) [ 300.589174] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 10:43:38 (1775832218) [ 304.824591] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 10:43:43 (1775832223) [ 309.341782] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 10:43:47 (1775832227) [ 310.407151] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 311.248549] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 10:43:49 (1775832229) [ 316.365937] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 10:43:54 (1775832234) [ 321.282268] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 10:43:59 (1775832239) [ 327.894186] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 10:44:05 (1775832245) [ 331.236293] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: disconnect after 20s idle [ 331.247190] Lustre: Skipped 1 previous similar message [ 334.443271] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 10:44:12 (1775832252) [ 340.594448] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 10:44:18 (1775832258) [ 345.944131] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 10:44:23 (1775832263) [ 346.593347] Lustre: lustre-OST0001-osc-ffff8ce1c9019800: disconnect after 24s idle [ 351.017330] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 10:44:29 (1775832269) [ 357.017877] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 10:44:35 (1775832275) [ 361.701100] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 10:44:39 (1775832279) [ 367.448569] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 10:44:45 (1775832285) [ 371.629919] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 10:44:49 (1775832289) [ 376.119952] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 10:44:54 (1775832294) [ 376.562307] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 522.002517] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 10:47:20 (1775832440) [ 526.996315] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 10:47:25 (1775832445) [ 530.558130] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 10:47:28 (1775832448) [ 533.704688] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 10:47:32 (1775832452) [ 537.068601] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 10:47:35 (1775832455) [ 540.371091] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 10:47:38 (1775832458) [ 541.188679] Lustre: DEBUG MARKER: chmod [ 544.388948] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 10:47:42 (1775832462) [ 568.721338] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7527424KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 578.652692] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 10:48:17 (1775832497) [ 602.475825] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 10:48:40 (1775832520) [ 615.519155] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 10:48:54 (1775832534) [ 616.554960] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 617.306162] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 10:48:55 (1775832535) [ 635.644385] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:49:14 (1775832554) [ 638.730375] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:49:17 (1775832557) [ 639.217468] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.281727] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.320270] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.348812] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.389286] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.425614] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.464176] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.499057] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.526701] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.563167] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.601676] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.640319] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.673229] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.704851] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.739425] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.774985] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.801878] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.832271] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.894523] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.928395] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.958229] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 639.988603] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.013101] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.043454] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.073923] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.105391] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.133219] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.167477] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.193907] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.229446] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.275393] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.313874] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.357149] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.396885] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.439882] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.478959] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.506926] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.537785] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.571610] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.603063] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.634704] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.675565] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.725796] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.763483] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.797977] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.828483] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.872890] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.899196] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.930936] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 640.963877] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.003337] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.046458] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.074881] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.108655] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.143932] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.176861] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.203475] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.233123] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.267110] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.300785] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.331368] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.384294] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.423333] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.455728] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.501645] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.543673] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.576382] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.617359] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.649880] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.700399] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.730490] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.759665] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.790262] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.825741] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.865959] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.894688] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.940331] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 641.975504] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.011828] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.051197] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.089834] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.130449] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.160706] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.195023] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.227912] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.262084] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.293483] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.324689] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.354246] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.388358] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.416530] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.447482] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.474996] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.505733] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.538279] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.572305] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.605560] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.647087] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.684818] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.722476] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.759516] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.802805] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.841767] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.872731] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.915991] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.953850] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 642.997397] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.032721] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.065714] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.098461] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.134494] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.164023] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.193822] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.233254] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.274765] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.309857] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.347590] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.397389] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.449619] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.491391] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.531423] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.576964] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.623547] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.662831] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.700163] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.732455] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.766939] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.809826] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.861586] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.893911] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.931382] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 643.966891] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.006355] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.034902] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.066099] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.101970] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.141668] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.172926] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.201978] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.229694] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.268364] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.322759] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.359240] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.395464] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.425905] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.464074] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.500751] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.532264] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.567642] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.619831] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.658441] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.703450] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.748814] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.784576] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.817277] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.855204] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.886134] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.915229] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.949027] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 644.985603] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.027060] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.061152] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.093200] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.134160] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.180447] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.220246] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.252691] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.285205] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.320854] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.360691] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.390130] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.420975] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.453200] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.489226] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.522981] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.550797] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.588690] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.615682] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.646561] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.675117] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.704034] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.729779] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.758599] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.789736] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.829384] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.866714] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.903569] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.940300] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 645.975750] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.011426] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.051381] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.085130] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.118683] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.153562] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.182717] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.211836] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.252890] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.285847] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.319919] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.360678] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.393088] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.425053] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.456937] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.500875] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.533868] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.561883] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.592144] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.622067] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.650639] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.690470] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.729591] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.770429] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.803640] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.831186] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.868559] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.904712] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.933712] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.962966] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 646.994794] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.025041] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.055722] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.091721] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.121522] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.168431] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.196985] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.239786] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.275892] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.307348] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.341146] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.375943] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.408593] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.437638] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.470867] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.503491] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.544751] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.584456] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.618546] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.656819] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.692414] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.725843] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.753870] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.789295] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.822124] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.849647] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.885286] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.920818] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.950375] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 647.977748] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.006574] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.042170] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.078496] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.108400] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.137322] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.169874] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.203659] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.236755] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.272989] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.308382] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.335874] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.363808] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.391553] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.420389] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.456475] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.485992] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.516472] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.544760] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.580716] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.610924] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.643477] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.673628] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.700875] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.732905] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.762548] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.792584] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.822628] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.854226] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.884092] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.914360] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 648.948308] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.004526] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.054027] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.088807] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.125153] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.157510] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.187818] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.216510] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.251707] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.282403] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.311432] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.350579] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.388768] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.427109] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.462841] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.500654] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.534790] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.569130] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.608471] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.641373] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.674679] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.711798] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.752872] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.790139] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.827529] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.862633] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.892288] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.924885] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.959861] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 649.990844] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.021224] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.053523] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.088916] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.121360] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.152359] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.193542] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.236859] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.276721] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.322901] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.354217] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.388202] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.424925] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.455577] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.492040] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.525892] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.554135] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.585292] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.617374] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.646638] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.684734] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.734040] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.782520] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.815519] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.851553] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.880647] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.920556] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.954152] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 650.990270] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.025770] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.056656] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.102201] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.153087] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.183547] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.218517] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.254049] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.288332] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.319945] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.358848] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.387317] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.413448] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.444722] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.479807] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.510958] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.540872] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.572460] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.606813] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.639544] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.684733] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.727505] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.767237] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.801634] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.829034] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.860423] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.897899] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.927137] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.956498] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 651.985800] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.020391] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.069856] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.113499] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.144429] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.180443] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.212034] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.243115] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.287750] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.320543] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.352752] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.386088] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.422238] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.456693] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.486455] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.518469] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.548237] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.581198] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.617138] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.652924] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.691266] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.722834] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.753925] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.784952] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.819579] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.853447] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.886478] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.920882] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.953508] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 652.987702] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.021827] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.056208] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.087240] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.117551] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.146089] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.176686] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.221331] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.270572] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.310797] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.344677] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.379470] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.410996] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.442163] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.468959] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.503386] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.537329] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.566847] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.597701] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.631552] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.668035] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.702618] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.735646] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.774459] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.812566] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.841548] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.869030] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.902579] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.928991] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.958806] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 653.989933] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.027544] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.066578] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.098531] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.135429] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.166360] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.197380] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.230213] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.264456] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.294309] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.324310] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.353990] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.384791] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.416715] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.447611] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.474905] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.506035] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.540747] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.571224] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.601834] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.633534] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.661917] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.698433] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.729798] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.763368] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.792237] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.824718] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.861848] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.892499] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.927287] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.958973] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 654.998773] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.037391] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.075485] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.110683] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.140067] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.168661] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.199369] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.226961] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.258928] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.292760] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.322492] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.354753] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.389961] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.421672] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.474898] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.517026] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.548559] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.575590] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.604710] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.633928] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.665469] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.703234] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.732283] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.771435] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.806446] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.856121] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.891714] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.937642] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 655.979171] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.018129] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.053284] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.095974] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.127871] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.157451] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.190429] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.224665] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.254250] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.286985] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.322387] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.356438] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.384555] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.411342] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.443575] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.475190] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.506945] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.539048] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.569804] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.601555] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.634431] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.661765] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.697347] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.737871] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.780043] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.811329] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.838212] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.867645] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.894948] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.925240] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.961768] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 656.993941] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.038906] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.081876] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.112251] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.147619] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.187847] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.227561] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.259483] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.289245] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.322894] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.365304] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.404719] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.438955] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.468976] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.502028] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.532556] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.563206] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.596786] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.629483] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.667972] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.697979] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.730638] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.762373] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.791594] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.827793] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.858729] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.885746] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.915043] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.944221] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 657.981118] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.008977] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.044491] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.071995] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.104304] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.135900] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.171936] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.214590] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.241717] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.273729] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.308114] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.348725] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.381403] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.428316] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.470322] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.506643] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.545083] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.574450] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.608187] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.643989] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.683294] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.713146] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.741458] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.771275] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.799675] rw_seq_cst_vs_d (29442): drop_caches: 3 [ 658.913248] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: disconnect after 20s idle [ 661.695169] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:49:40 (1775832580) [ 661.898174] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 661.920304] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 661.962725] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.046690] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.068532] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.086631] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.110228] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.176326] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.195617] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.213246] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.234496] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.252408] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.393914] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.421206] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.446218] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.502349] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.532111] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.575475] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.634406] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.656751] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.677236] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.696208] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.718147] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.737627] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.756429] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.787353] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 662.969753] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.034428] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.066193] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.084196] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.116326] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.137174] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.184534] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.203510] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.252448] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.286513] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.340665] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.366829] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.386947] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.408295] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.449380] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.467274] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.503757] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.528755] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.575359] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.595406] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.617178] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.708371] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.741161] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.774150] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.796924] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.816145] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.835533] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.856038] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.880465] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.906248] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 663.941494] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.021227] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.376852] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.424232] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.454189] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.536440] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.594555] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.612556] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.659659] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.694071] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.721567] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.779175] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.857570] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.876205] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.906819] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.924782] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 664.976408] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.006176] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.053948] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.077489] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.184117] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.213956] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.236421] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.268128] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.310321] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.333387] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.357754] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.412053] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.438142] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.495407] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.591571] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.628908] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.718813] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.751487] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.882931] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 665.913160] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.147962] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.182914] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.205995] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.229137] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.257437] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.280745] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.323687] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.446718] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.476150] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 666.514987] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.131976] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.158708] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.185474] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.211844] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.275658] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.347191] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.401449] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.424266] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.453312] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.483472] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.509596] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.669646] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.690190] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.720045] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.749715] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.784190] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.877035] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 667.938527] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.141280] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.249773] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.276407] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.299652] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.324824] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.345602] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.364764] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.389581] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.444776] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.463969] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.482826] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.502738] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.532409] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.782816] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.855852] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.948875] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 668.979810] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.040740] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.182257] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.204675] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.234356] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.253870] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.280481] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.298718] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.320804] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.341089] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.367251] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.623961] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.809433] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.838371] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.860803] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.889799] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.914638] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.960619] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 669.984353] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.017027] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.059431] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.126061] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.183762] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.206015] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.224095] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.244695] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.276164] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.296853] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.314046] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.332857] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.351611] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.387922] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.412341] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.484323] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.531217] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.566566] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.711104] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.735783] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.851420] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.884741] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.931461] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.954786] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 670.981665] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.004137] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.029519] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.052867] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.084171] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.121844] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.233901] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.268093] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.302660] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.332984] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.350531] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.370129] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.390910] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.412236] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.435697] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.455785] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.477208] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.498064] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.520959] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.539756] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.577316] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.597528] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.644439] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.667854] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.699815] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.739855] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.759717] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.783129] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.806529] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.835216] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.854185] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.879291] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 671.984148] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.039094] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.081503] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.107459] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.133511] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.154100] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.189072] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.223419] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.263095] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.288347] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.308777] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.355877] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.388504] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.407959] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.467032] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.485500] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.508978] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.534946] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.557332] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.586042] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.774132] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.808212] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.833101] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.850894] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 672.878970] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.060491] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.161054] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.212884] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.235102] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.254658] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.286924] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.305245] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.328574] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.344453] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.367750] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.400638] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.421942] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.452792] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.473049] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.492540] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.511502] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.665240] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.820754] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.840896] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.884967] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.906183] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.931325] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.956057] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 673.974023] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.148642] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.197371] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.235272] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.255479] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.866693] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 674.892383] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.066481] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.214599] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.254226] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.275031] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.295165] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.316046] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.338548] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.367498] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.384816] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.402759] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.448910] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.775963] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.851583] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.878405] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.900453] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.926900] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.959116] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 675.982339] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.005824] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.171458] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.194856] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.271798] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.293314] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.370388] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.401893] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.428041] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.460267] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.533217] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.553664] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.574658] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.603699] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.625411] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.685724] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.722987] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.769568] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.793639] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.841902] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 676.991707] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.033598] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.061569] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.084056] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.119215] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.175805] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.195793] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.248978] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.272326] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.296338] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.324456] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.541385] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.641822] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.666085] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.692139] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.733961] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.782150] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.803981] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.843275] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.881293] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 677.906196] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.015289] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.036770] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.055501] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.214093] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.235707] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.258251] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.277660] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.297683] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.318082] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.388874] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.415859] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.436789] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.793092] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 678.938075] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.270337] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.364553] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.389128] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.392182] Lustre: lustre-OST0001-osc-ffff8ce1c9019800: disconnect after 21s idle [ 679.396918] Lustre: Skipped 1 previous similar message [ 679.629823] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.726849] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.752312] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.787264] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.812172] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.852252] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.877316] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.900732] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.951059] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.972406] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 679.995028] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.017553] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.036975] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.055219] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.075048] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.092733] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.274894] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.300831] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.324517] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.344117] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 680.655485] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.064908] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.100831] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.144320] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.173074] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.202739] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.236511] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.258412] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.281258] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.308602] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.331084] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.353057] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.580774] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.600950] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.618947] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.637332] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 681.656596] rw_seq_cst_vs_d (30019): drop_caches: 3 [ 685.008420] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 10:50:03 (1775832603) [ 687.960134] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:50:06 (1775832606) [ 690.872812] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 10:50:09 (1775832609) [ 706.684918] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 10:50:25 (1775832625) [ 706.974126] bash (32463): drop_caches: 3 [ 710.569229] bash (32463): drop_caches: 3 [ 713.636669] bash (32463): drop_caches: 3 [ 716.701349] bash (32463): drop_caches: 3 [ 719.774374] bash (32463): drop_caches: 3 [ 722.861429] bash (32463): drop_caches: 3 [ 726.850864] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 10:50:45 (1775832645) [ 732.369143] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 10:50:50 (1775832650) [ 748.798655] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 10:51:07 (1775832667) [ 749.951079] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 750.650771] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 10:51:09 (1775832669) [ 753.637332] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 10:51:12 (1775832672) [ 756.511680] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 10:51:15 (1775832675) [ 766.432355] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: disconnect after 22s idle [ 766.437303] Lustre: Skipped 1 previous similar message [ 821.228899] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 10:52:19 (1775832739) [ 824.353031] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 10:52:22 (1775832742) [ 827.230774] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 10:52:25 (1775832745) [ 830.520753] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 10:52:29 (1775832749) [ 831.204703] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 832.002623] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 10:52:30 (1775832750) [ 835.528045] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 10:52:34 (1775832754) [ 839.893871] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 10:52:38 (1775832758) [ 843.261173] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 843.867535] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 10:52:42 (1775832762) [ 848.525123] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 10:52:47 (1775832767) [ 848.640172] Lustre: *** cfs_fail_loc=314, val=0*** [ 849.696214] Lustre: *** cfs_fail_loc=314, val=0*** [ 849.698767] Lustre: Skipped 2 previous similar messages [ 852.086736] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 10:52:50 (1775832770) [ 861.599859] Lustre: *** cfs_fail_loc=314, val=0*** [ 861.636536] LustreError: lustre-OST0000-osc-ffff8ce1c9019800: operation ldlm_enqueue to node 192.168.204.147@tcp failed: rc = -107 [ 861.642415] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: Connection to lustre-OST0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 861.653634] LustreError: lustre-OST0000-osc-ffff8ce1c9019800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 861.664594] LustreError: 41677:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8ce1c9019800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8ce1c5341600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 861.677997] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 864.761333] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 10:53:03 (1775832783) [ 864.870704] LustreError: 42259:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 867.888380] LustreError: 42259:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 870.580550] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 10:53:09 (1775832789) [ 873.954582] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 10:53:12 (1775832792) [ 877.665141] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 878.384879] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 10:53:16 (1775832796) [ 879.159642] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 879.869413] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 10:53:18 (1775832798) [ 880.571843] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 881.282101] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 10:53:19 (1775832799) [ 881.984310] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 882.765735] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 10:53:21 (1775832801) [ 883.478986] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 884.227511] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 10:53:22 (1775832802) [ 939.449923] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: Connection to lustre-OST0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 939.461856] LustreError: lustre-OST0000-osc-ffff8ce1c9019800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 939.468325] LustreError: lustre-OST0000-osc-ffff8ce1d063e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 939.468960] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 939.478473] Lustre: Skipped 1 previous similar message [ 950.762827] Lustre: lustre-OST0001-osc-ffff8ce1d063e800: Connection to lustre-OST0001 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 950.770657] Lustre: Skipped 1 previous similar message [ 950.777617] LustreError: lustre-OST0001-osc-ffff8ce1d063e800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 950.785957] Lustre: lustre-OST0001-osc-ffff8ce1d063e800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 960.992233] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: disconnect after 22s idle [ 960.997562] Lustre: Skipped 3 previous similar messages [ 965.010752] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 965.610860] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid in IDLE state after 0 sec [ 967.130636] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 967.791556] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid in FULL state after 0 sec [ 970.000850] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 970.628923] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid in IDLE state after 0 sec [ 972.333348] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 973.034501] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid in FULL state after 0 sec [ 977.248770] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 977.872092] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ce1c9019800.ost_server_uuid in IDLE state after 0 sec [ 979.591875] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid 50 [ 980.271617] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8ce1c9019800.ost_server_uuid in FULL state after 0 sec [ 980.981529] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 10:54:59 (1775832899) [ 982.279381] Lustre: DEBUG MARKER: Race attempt 0 [ 984.013287] Lustre: DEBUG MARKER: Wait for 48877 48999 for 60 sec... [ 1046.849326] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 10:56:05 (1775832965) [ 1052.300904] Lustre: DEBUG MARKER: start test - cycle (0) [ 1074.192105] Lustre: DEBUG MARKER: start test - cycle (1) [ 1094.503632] Lustre: DEBUG MARKER: start test - cycle (2) [ 1114.968717] Lustre: DEBUG MARKER: start test - cycle (3) [ 1135.829480] Lustre: DEBUG MARKER: start test - cycle (4) [ 1156.006238] Lustre: DEBUG MARKER: start test - cycle (5) [ 1176.087942] Lustre: DEBUG MARKER: start test - cycle (6) [ 1196.689674] Lustre: DEBUG MARKER: start test - cycle (7) [ 1217.061376] Lustre: DEBUG MARKER: start test - cycle (8) [ 1237.249342] Lustre: DEBUG MARKER: start test - cycle (9) [ 1258.551771] Lustre: DEBUG MARKER: start test - cycle (10) [ 1280.746277] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 10:59:59 (1775833199) [ 1288.672293] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: disconnect after 23s idle [ 1288.677679] Lustre: Skipped 2 previous similar messages [ 1315.284109] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 11:00:33 (1775833233) [ 1318.162474] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 11:00:36 (1775833236) [ 1322.017765] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 11:00:40 (1775833240) [ 1325.604111] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 11:00:44 (1775833244) [ 1325.709753] Lustre: *** cfs_fail_loc=411, val=0*** [ 1328.157383] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1328.780407] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 11:00:47 (1775833247) [ 1338.029486] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 11:00:56 (1775833256) [ 1347.316315] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 11:01:05 (1775833265) [ 1356.515430] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 11:01:15 (1775833275) [ 1364.904255] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 11:01:23 (1775833283) [ 1370.645954] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 11:01:29 (1775833289) [ 1376.306650] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 11:01:34 (1775833294) [ 1381.692992] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 11:01:40 (1775833300) [ 1387.194788] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 11:01:45 (1775833305) [ 1392.505774] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 11:01:51 (1775833311) [ 1398.027682] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 11:01:56 (1775833316) [ 1403.736286] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 11:02:02 (1775833322) [ 1409.256791] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 11:02:07 (1775833327) [ 1426.912271] Lustre: lustre-OST0001-osc-ffff8ce1d063e800: disconnect after 23s idle [ 1426.916280] Lustre: Skipped 4 previous similar messages [ 2031.072186] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: disconnect after 23s idle [ 2031.076028] Lustre: Skipped 1 previous similar message [ 2132.098974] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 11:14:10 (1775834050) [ 2136.983775] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 11:14:15 (1775834055) [ 2142.008578] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 11:14:20 (1775834060) [ 2147.148475] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 11:14:25 (1775834065) [ 2152.187449] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 11:14:30 (1775834070) [ 2157.264956] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 11:14:35 (1775834075) [ 2162.208518] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 11:14:40 (1775834080) [ 2167.184614] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 11:14:45 (1775834085) [ 2172.096728] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 11:14:50 (1775834090) [ 2188.835485] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 11:15:07 (1775834107) [ 2193.786298] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 11:15:12 (1775834112) [ 2198.705031] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 11:15:17 (1775834117) [ 2203.750802] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 11:15:22 (1775834122) [ 2208.795829] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 11:15:27 (1775834127) [ 2213.623044] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 11:15:32 (1775834132) [ 2218.556394] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 11:15:37 (1775834137) [ 2223.505975] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 11:15:42 (1775834142) [ 2223.994657] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2224.546377] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 11:15:43 (1775834143) [ 2261.473150] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 11:16:20 (1775834180) [ 2684.819123] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:23:23 (1775834603) [ 2690.070854] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:23:28 (1775834608) [ 2695.322748] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:23:33 (1775834613) [ 2700.456628] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:23:39 (1775834619) [ 2705.617264] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:23:44 (1775834624) [ 2710.738467] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:23:49 (1775834629) [ 2715.850812] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:23:54 (1775834634) [ 2720.964946] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:23:59 (1775834639) [ 2726.075829] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:24:04 (1775834644) [ 2726.587803] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2727.168668] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:24:05 (1775834645) [ 2742.752100] Lustre: lustre-OST0000-osc-ffff8ce1d063e800: disconnect after 22s idle [ 2742.754392] Lustre: Skipped 5 previous similar messages [ 2755.604632] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:24:34 (1775834674) [ 2760.683629] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:24:39 (1775834679) [ 2765.939209] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:24:44 (1775834684) [ 2771.206663] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:24:49 (1775834689) [ 2776.314128] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:24:55 (1775834695) [ 2781.112938] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:24:59 (1775834699) [ 2786.215264] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:25:04 (1775834704) [ 2790.808826] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:25:09 (1775834709) [ 2791.306587] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2791.856715] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:25:10 (1775834710) [ 3207.860322] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:32:06 (1775835126) [ 3212.922165] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:32:11 (1775835131) [ 3217.926861] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:32:16 (1775835136) [ 3222.982759] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:32:21 (1775835141) [ 3227.965208] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:32:26 (1775835146) [ 3232.997766] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:32:31 (1775835151) [ 3238.065841] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:32:36 (1775835156) [ 3243.217916] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:32:41 (1775835161) [ 3248.283373] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:32:46 (1775835166) [ 3248.734803] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3249.264131] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:32:47 (1775835167) [ 3249.748752] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3250.311113] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:32:48 (1775835168) [ 3250.829429] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3251.404770] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:32:50 (1775835170) [ 3251.919173] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3252.484499] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:32:51 (1775835171) [ 3253.014684] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3253.615684] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:32:52 (1775835172) [ 3254.111527] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3254.637352] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:32:53 (1775835173) [ 3255.108403] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3255.645371] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:32:54 (1775835174) [ 3256.124512] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3256.653755] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:32:55 (1775835175) [ 3256.728150] LustreError: 5539:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3258.808102] LustreError: 5539:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3263.848647] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:33:02 (1775835182) [ 3267.960406] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:33:06 (1775835186) [ 3268.053503] LustreError: 217180:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3272.112134] LustreError: 217180:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3272.121350] LustreError: 217180:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3276.184132] LustreError: 217180:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3276.196837] LustreError: 217187:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3280.256070] LustreError: 217187:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3282.461823] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:33:21 (1775835201) [ 3288.986754] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:33:27 (1775835207) [ 3292.088145] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:33:30 (1775835210) [ 3296.159729] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:33:34 (1775835214) [ 3320.268372] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:33:58 (1775835238) [ 3327.796400] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:34:06 (1775835246) [ 3335.374420] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:34:14 (1775835254) [ 3348.187126] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:34:26 (1775835266) [ 3357.694690] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 11:34:36 (1775835276) [ 3358.183956] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3358.726368] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 11:34:37 (1775835277) [ 3371.563878] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 11:34:50 (1775835290) [ 3385.672053] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 11:35:04 (1775835304) [ 3410.575486] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 11:35:29 (1775835329) [ 3449.435127] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:36:08 (1775835368) [ 3451.734493] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3453.998888] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:36:12 (1775835372) [ 3456.766179] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:36:15 (1775835375) [ 3457.670809] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3458.670160] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:36:17 (1775835377) [ 3459.397995] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3459.918627] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:36:18 (1775835378) [ 3460.401622] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3460.914273] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:36:19 (1775835379) [ 3461.422575] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3461.961112] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:36:20 (1775835380) [ 3464.120602] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:36:22 (1775835382) [ 3466.323261] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:36:24 (1775835384) [ 3469.391695] LustreError: lustre-MDT0000-mdc-ffff8ce1c9019800: operation ldlm_enqueue to node 192.168.204.147@tcp failed: rc = -35 [ 3472.417505] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:36:31 (1775835391) [ 3472.564949] LustreError: 2367:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3474.648127] LustreError: 2367:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3479.587059] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:36:38 (1775835398) [ 3498.405824] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:36:57 (1775835417) [ 3501.388888] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:37:00 (1775835420) [ 3505.360017] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:37:04 (1775835424) [ 3510.133587] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:37:08 (1775835428) [ 3515.019369] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:37:13 (1775835433) [ 3520.993162] Lustre: lustre-OST0000-osc-ffff8ce1c9019800: disconnect after 22s idle [ 3520.995047] Lustre: Skipped 4 previous similar messages [ 3522.803572] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:37:21 (1775835441) [ 3530.126558] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:37:28 (1775835448) [ 3533.517715] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:37:32 (1775835452) [ 3537.292320] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:37:35 (1775835455) [ 3544.411835] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:37:43 (1775835463) [ 3589.745551] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:38:28 (1775835508) [ 3720.918890] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 11:40:39 (1775835639) [ 3853.974324] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 11:42:52 (1775835772) [ 4150.752237] Lustre: lustre-OST0001-osc-ffff8ce1d063e800: disconnect after 21s idle [ 4150.754873] Lustre: Skipped 15 previous similar messages [ 4203.537147] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 11:48:42 (1775836122) [ 4239.181452] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 11:49:17 (1775836157) [ 4275.551520] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 11:49:54 (1775836194) [ 4306.825356] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 11:50:25 (1775836225) [ 4383.263812] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 11:51:41 (1775836301) [ 4447.190863] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 11:52:45 (1775836365) [ 4561.713044] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 11:54:40 (1775836480) [ 4562.691668] LustreError: 258055:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d063e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4562.704910] Lustre: Unmounted lustre-client [ 4563.598146] LustreError: 258068:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c9019800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4563.601507] LustreError: 258068:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 4563.614072] Lustre: Unmounted lustre-client [ 4604.837180] Lustre: Mounted lustre-client [ 4606.328763] Lustre: Mounted lustre-client [ 4607.273532] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4693.765696] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 11:56:52 (1775836612) [ 4701.226895] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:56:59 (1775836619) [ 4704.377799] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:57:03 (1775836623) [ 4754.534853] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:57:53 (1775836673) [ 4780.512191] Lustre: lustre-OST0001-osc-ffff8ce1f04ef000: disconnect after 20s idle [ 4780.514917] Lustre: Skipped 14 previous similar messages [ 4820.570996] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:58:59 (1775836739) [ 4824.385689] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:59:03 (1775836743) [ 4861.658919] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:59:40 (1775836780) [ 4874.425011] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 11:59:53 (1775836793) [ 4914.677459] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 12:00:33 (1775836833) [ 4917.529732] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 12:00:36 (1775836836) [ 4930.438425] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 12:00:49 (1775836849) [ 4930.931244] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 4931.438020] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 12:00:50 (1775836850) [ 4931.894563] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 4932.418529] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 12:00:51 (1775836851) [ 4932.891949] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 4933.398891] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 12:00:52 (1775836852) [ 4933.861598] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 4934.344890] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 12:00:53 (1775836853) [ 4934.883860] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4935.496915] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 12:00:54 (1775836854) [ 4965.504727] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 12:01:24 (1775836884) [ 4967.886848] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 12:01:26 (1775836886) [ 4968.384684] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 4969.095813] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 12:01:27 (1775836887) [ 4976.516101] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 12:01:35 (1775836895) [ 4979.200404] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 12:01:37 (1775836897) [ 4979.667449] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 4980.221203] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 12:01:38 (1775836898) [ 4980.695958] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 4981.222301] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 12:01:39 (1775836899) [ 4981.694546] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 4982.219858] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 12:01:40 (1775836900) [ 4991.601337] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 12:01:50 (1775836910) [ 4991.678689] Lustre: DEBUG MARKER: write [ 4991.692602] LustreError: 259467:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 4993.696756] Lustre: DEBUG MARKER: kill 288222 [ 4993.698733] LustreError: 288222:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 4996.792140] LustreError: 259467:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 4999.736177] LustreError: 288222:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5001.982906] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 12:02:00 (1775836920) [ 5004.153555] LustreError: 288827:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5014.248094] LustreError: 288827:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5016.952037] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 12:02:15 (1775836935) [ 5017.079296] LustreError: 289408:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5019.160194] LustreError: 289408:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 5025.545611] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 12:02:24 (1775836944) [ 5026.117295] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5026.713991] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 12:02:25 (1775836945) [ 5028.906356] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 12:02:27 (1775836947) [ 5031.455014] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 12:02:29 (1775836949) [ 5033.999676] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 12:02:32 (1775836952) [ 5036.540055] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 12:02:35 (1775836955) [ 5038.871987] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 12:02:37 (1775836957) [ 5041.248410] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 12:02:39 (1775836959) [ 5044.658923] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5045.249884] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 12:02:43 (1775836963) [ 5045.996681] Lustre: *** cfs_fail_loc=415, val=0*** [ 5052.785822] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 12:02:51 (1775836971) [ 5053.286103] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5053.808751] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 12:02:52 (1775836972) [ 5053.868835] LustreError: 273566:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5053.872839] LustreError: 273566:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 5058.872063] LustreError: 273566:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5069.072114] LustreError: 273566:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5069.074317] LustreError: 273566:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 5 previous similar messages [ 5076.165349] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 12:03:14 (1775836994) [ 5076.604467] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5077.098909] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 12:03:15 (1775836995) [ 5079.296432] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 12:03:17 (1775836997) [ 5081.375220] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 12:03:20 (1775837000) [ 5085.499080] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 12:03:24 (1775837004) [ 5093.751870] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 12:03:32 (1775837012) [ 5093.893070] LustreError: 249473:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5093.897047] LustreError: 249473:0:(osc_request.c:2989:osc_build_rpc()) Skipped 5 previous similar messages [ 5097.960100] LustreError: 249473:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 5097.965313] LustreError: 249473:0:(osc_request.c:2989:osc_build_rpc()) Skipped 2 previous similar messages [ 5100.261846] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 12:03:38 (1775837018) [ 5100.949653] LustreError: 299351:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1f04ef000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5100.952577] LustreError: 299351:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5100.966982] Lustre: Unmounted lustre-client [ 5101.849921] Lustre: DEBUG MARKER: Iteration 1 [ 5101.967847] LustreError: 299531:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5101.967879] LustreError: 299532:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5101.973275] LustreError: 299531:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5102.018609] Lustre: Mounted lustre-client [ 5102.020236] Lustre: Skipped 1 previous similar message [ 5103.382176] Key type lgssc unregistered [ 5103.493483] LNet: 299877:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5103.495635] LNetError: 299877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5103.504953] LNet: Removed LNI 192.168.204.47@tcp [ 5103.786101] Key type .llcrypt unregistered [ 5103.787213] Key type ._llcrypt unregistered [ 5104.047815] Key type ._llcrypt registered [ 5104.050931] Key type .llcrypt registered [ 5104.289110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5104.294280] alg: No test for adler32 (adler32-zlib) [ 5105.246461] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5105.507732] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5107.120138] Key type lgssc registered [ 5107.639830] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5112.165288] Lustre: DEBUG MARKER: Iteration 2 [ 5112.288939] LustreError: 300697:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5112.289933] LustreError: 300698:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5112.293734] LustreError: 300697:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5113.339392] Lustre: Mounted lustre-client [ 5113.340860] Lustre: Skipped 1 previous similar message [ 5113.786856] LustreError: 300806:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4afd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5113.789868] LustreError: 300806:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5113.809051] Lustre: Unmounted lustre-client [ 5114.716748] Key type lgssc unregistered [ 5114.838763] LNet: 301049:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5114.844100] LNetError: 301049:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5114.855839] LNet: Removed LNI 192.168.204.47@tcp [ 5115.131158] Key type .llcrypt unregistered [ 5115.133554] Key type ._llcrypt unregistered [ 5115.394408] Key type ._llcrypt registered [ 5115.395447] Key type .llcrypt registered [ 5115.584142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5115.589086] alg: No test for adler32 (adler32-zlib) [ 5116.447673] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5116.531579] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5118.112100] Key type lgssc registered [ 5118.494310] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5121.878979] Lustre: DEBUG MARKER: Iteration 3 [ 5121.994314] LustreError: 301870:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5121.997064] LustreError: 301871:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5121.999674] LustreError: 301870:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5123.048481] Lustre: Mounted lustre-client [ 5123.050412] Lustre: Skipped 1 previous similar message [ 5123.471618] LustreError: 301977:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cfd0e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5123.542339] Lustre: Unmounted lustre-client [ 5123.543469] Lustre: Skipped 1 previous similar message [ 5124.415852] Key type lgssc unregistered [ 5124.527420] LNet: 302221:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5124.529775] LNetError: 302221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5124.536665] LNet: Removed LNI 192.168.204.47@tcp [ 5124.744129] Key type .llcrypt unregistered [ 5124.745242] Key type ._llcrypt unregistered [ 5125.016937] Key type ._llcrypt registered [ 5125.018430] Key type .llcrypt registered [ 5125.184911] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5125.189494] alg: No test for adler32 (adler32-zlib) [ 5126.037446] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5126.112337] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5127.696153] Key type lgssc registered [ 5128.121255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5131.750593] Lustre: DEBUG MARKER: Iteration 4 [ 5131.859515] LustreError: 303041:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5131.859602] LustreError: 303042:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5131.863742] LustreError: 303041:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5132.916189] Lustre: Mounted lustre-client [ 5132.917316] Lustre: Skipped 1 previous similar message [ 5133.342822] LustreError: 303143:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c9f1d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5133.393900] Lustre: Unmounted lustre-client [ 5134.253418] Key type lgssc unregistered [ 5134.359345] LNet: 303387:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5134.361844] LNetError: 303387:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5134.369693] LNet: Removed LNI 192.168.204.47@tcp [ 5134.586104] Key type .llcrypt unregistered [ 5134.587118] Key type ._llcrypt unregistered [ 5134.808978] Key type ._llcrypt registered [ 5134.810494] Key type .llcrypt registered [ 5135.032989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5135.037580] alg: No test for adler32 (adler32-zlib) [ 5135.948701] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5136.066296] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5137.680135] Key type lgssc registered [ 5138.046073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5141.475280] Lustre: DEBUG MARKER: Iteration 5 [ 5141.569299] LustreError: 304207:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5141.569347] LustreError: 304208:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5141.573346] LustreError: 304207:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5142.624853] Lustre: Mounted lustre-client [ 5143.055063] LustreError: 304316:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5143.059201] LustreError: 304316:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5143.084093] Lustre: Unmounted lustre-client [ 5143.085175] Lustre: Skipped 1 previous similar message [ 5144.013659] Key type lgssc unregistered [ 5144.130548] LNet: 304558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5144.132940] LNetError: 304558:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5144.141692] LNet: Removed LNI 192.168.204.47@tcp [ 5144.366109] Key type .llcrypt unregistered [ 5144.367821] Key type ._llcrypt unregistered [ 5144.622969] Key type ._llcrypt registered [ 5144.627419] Key type .llcrypt registered [ 5144.806255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5144.811213] alg: No test for adler32 (adler32-zlib) [ 5145.658105] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5145.733384] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5147.312175] Key type lgssc registered [ 5147.711333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5151.023906] Lustre: DEBUG MARKER: Iteration 6 [ 5151.125548] LustreError: 305377:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5151.125591] LustreError: 305379:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5151.129877] LustreError: 305377:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5152.175613] Lustre: Mounted lustre-client [ 5152.176705] Lustre: Skipped 1 previous similar message [ 5152.622940] LustreError: 305480:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cfd0f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5152.626105] LustreError: 305480:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5152.647946] Lustre: Unmounted lustre-client [ 5153.553489] Key type lgssc unregistered [ 5153.665545] LNet: 305723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5153.667716] LNetError: 305723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5153.677761] LNet: Removed LNI 192.168.204.47@tcp [ 5153.901121] Key type .llcrypt unregistered [ 5153.902971] Key type ._llcrypt unregistered [ 5154.196073] Key type ._llcrypt registered [ 5154.197106] Key type .llcrypt registered [ 5154.355586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5154.361396] alg: No test for adler32 (adler32-zlib) [ 5155.213777] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5155.290109] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5156.872100] Key type lgssc registered [ 5157.284562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5160.580993] Lustre: DEBUG MARKER: Iteration 7 [ 5160.674365] LustreError: 306544:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5160.676584] LustreError: 306546:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5160.678936] LustreError: 306544:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5161.728501] Lustre: Mounted lustre-client [ 5161.729878] Lustre: Skipped 1 previous similar message [ 5162.152477] LustreError: 306653:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cdb73800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5162.156889] LustreError: 306653:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5162.186072] Lustre: Unmounted lustre-client [ 5163.072468] Key type lgssc unregistered [ 5163.183546] LNet: 306896:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5163.187508] LNetError: 306896:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5163.198763] LNet: Removed LNI 192.168.204.47@tcp [ 5163.424095] Key type .llcrypt unregistered [ 5163.425140] Key type ._llcrypt unregistered [ 5163.674776] Key type ._llcrypt registered [ 5163.675876] Key type .llcrypt registered [ 5163.846654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5163.851513] alg: No test for adler32 (adler32-zlib) [ 5164.708146] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5164.796203] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5166.376129] Key type lgssc registered [ 5166.823382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5170.152784] Lustre: DEBUG MARKER: Iteration 8 [ 5170.248885] LustreError: 307717:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5170.248940] LustreError: 307718:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5170.254358] LustreError: 307717:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5171.295426] Lustre: Mounted lustre-client [ 5171.717697] LustreError: 307821:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a7d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5171.721474] LustreError: 307821:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5171.742084] Lustre: Unmounted lustre-client [ 5172.686184] Key type lgssc unregistered [ 5172.802597] LNet: 308063:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5172.806214] LNetError: 308063:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5172.816758] LNet: Removed LNI 192.168.204.47@tcp [ 5173.049116] Key type .llcrypt unregistered [ 5173.050283] Key type ._llcrypt unregistered [ 5173.322848] Key type ._llcrypt registered [ 5173.323907] Key type .llcrypt registered [ 5173.489834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5173.494752] alg: No test for adler32 (adler32-zlib) [ 5174.345260] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5174.422431] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5176.000148] Key type lgssc registered [ 5176.445606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5179.938799] Lustre: DEBUG MARKER: Iteration 9 [ 5180.053200] LustreError: 308883:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5180.055321] LustreError: 308884:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5180.058357] LustreError: 308883:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5181.101609] Lustre: Mounted lustre-client [ 5181.544520] LustreError: 308990:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1fabaf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5181.548364] LustreError: 308990:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5181.593101] Lustre: Unmounted lustre-client [ 5182.709130] Key type lgssc unregistered [ 5182.865556] LNet: 309233:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5182.868563] LNetError: 309233:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5182.880977] LNet: Removed LNI 192.168.204.47@tcp [ 5183.214167] Key type .llcrypt unregistered [ 5183.215554] Key type ._llcrypt unregistered [ 5183.518047] Key type ._llcrypt registered [ 5183.523788] Key type .llcrypt registered [ 5183.692620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5183.698610] alg: No test for adler32 (adler32-zlib) [ 5184.560374] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5184.647718] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5186.248192] Key type lgssc registered [ 5186.829253] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5190.516040] Lustre: DEBUG MARKER: Iteration 10 [ 5190.613780] LustreError: 310054:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5190.614110] LustreError: 310055:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5190.619075] LustreError: 310054:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5191.659108] Lustre: Mounted lustre-client [ 5191.661103] Lustre: Skipped 1 previous similar message [ 5192.086901] LustreError: 310160:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d08d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5192.091637] LustreError: 310160:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5192.109472] Lustre: Unmounted lustre-client [ 5193.055101] Key type lgssc unregistered [ 5193.180829] LNet: 310401:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5193.184698] LNetError: 310401:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5193.195862] LNet: Removed LNI 192.168.204.47@tcp [ 5193.498157] Key type .llcrypt unregistered [ 5193.499868] Key type ._llcrypt unregistered [ 5193.782467] Key type ._llcrypt registered [ 5193.783951] Key type .llcrypt registered [ 5193.964513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5193.969365] alg: No test for adler32 (adler32-zlib) [ 5194.840444] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5194.928505] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5196.520204] Key type lgssc registered [ 5196.926205] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5200.455488] Lustre: DEBUG MARKER: Iteration 11 [ 5200.566861] LustreError: 311222:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5200.566902] LustreError: 311223:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5200.571056] LustreError: 311222:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5201.612215] Lustre: Mounted lustre-client [ 5201.614069] Lustre: Skipped 1 previous similar message [ 5202.037278] LustreError: 311327:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4af8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5202.041104] LustreError: 311327:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5202.070095] Lustre: Unmounted lustre-client [ 5203.133337] Key type lgssc unregistered [ 5203.249619] LNet: 311569:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5203.253233] LNetError: 311569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5203.262832] LNet: Removed LNI 192.168.204.47@tcp [ 5203.517142] Key type .llcrypt unregistered [ 5203.520640] Key type ._llcrypt unregistered [ 5203.808134] Key type ._llcrypt registered [ 5203.809830] Key type .llcrypt registered [ 5203.982278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5203.994585] alg: No test for adler32 (adler32-zlib) [ 5204.855556] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5204.940528] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5206.528125] Key type lgssc registered [ 5207.168130] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5210.974746] Lustre: DEBUG MARKER: Iteration 12 [ 5211.101447] LustreError: 312400:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5211.102457] LustreError: 312401:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5211.107054] LustreError: 312400:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5212.147813] Lustre: Mounted lustre-client [ 5212.657953] LustreError: 312502:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1f51b0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5212.718980] Lustre: Unmounted lustre-client [ 5213.697684] Key type lgssc unregistered [ 5213.806767] LNet: 312746:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5213.809218] LNetError: 312746:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5213.819329] LNet: Removed LNI 192.168.204.47@tcp [ 5214.054110] Key type .llcrypt unregistered [ 5214.055677] Key type ._llcrypt unregistered [ 5214.354653] Key type ._llcrypt registered [ 5214.355621] Key type .llcrypt registered [ 5214.559580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5214.567762] alg: No test for adler32 (adler32-zlib) [ 5215.421101] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5215.511026] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5217.096123] Key type lgssc registered [ 5217.535418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5220.999678] Lustre: DEBUG MARKER: Iteration 13 [ 5221.096918] LustreError: 313567:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5221.097057] LustreError: 313569:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5221.101214] LustreError: 313567:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5222.143094] Lustre: Mounted lustre-client [ 5222.145475] Lustre: Skipped 1 previous similar message [ 5222.558039] LustreError: 313675:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cb56b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5222.584369] Lustre: Unmounted lustre-client [ 5223.481064] Key type lgssc unregistered [ 5223.583516] LNet: 313918:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5223.585865] LNetError: 313918:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5223.594448] LNet: Removed LNI 192.168.204.47@tcp [ 5223.805083] Key type .llcrypt unregistered [ 5223.806281] Key type ._llcrypt unregistered [ 5224.071049] Key type ._llcrypt registered [ 5224.072158] Key type .llcrypt registered [ 5224.243918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5224.249388] alg: No test for adler32 (adler32-zlib) [ 5225.106379] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5225.186941] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5226.768189] Key type lgssc registered [ 5227.153333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5230.455483] Lustre: DEBUG MARKER: Iteration 14 [ 5230.559321] LustreError: 314739:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5230.559352] LustreError: 314738:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5230.564722] LustreError: 314739:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5231.616450] Lustre: Mounted lustre-client [ 5232.054609] LustreError: 314846:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1fac8f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5232.058963] LustreError: 314846:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5232.079508] Lustre: Unmounted lustre-client [ 5232.973163] Key type lgssc unregistered [ 5233.092514] LNet: 315088:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5233.094715] LNetError: 315088:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5233.104797] LNet: Removed LNI 192.168.204.47@tcp [ 5233.315122] Key type .llcrypt unregistered [ 5233.316246] Key type ._llcrypt unregistered [ 5233.530235] Key type ._llcrypt registered [ 5233.537158] Key type .llcrypt registered [ 5233.748399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5233.754189] alg: No test for adler32 (adler32-zlib) [ 5234.605305] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5234.687772] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5236.264169] Key type lgssc registered [ 5236.639331] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5239.855263] Lustre: DEBUG MARKER: Iteration 15 [ 5239.951024] LustreError: 315908:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5239.953544] LustreError: 315909:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5239.955560] LustreError: 315908:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5241.000104] Lustre: Mounted lustre-client [ 5241.447791] LustreError: 316015:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a7e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5241.452363] LustreError: 316015:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5241.463089] Lustre: Unmounted lustre-client [ 5242.340036] Key type lgssc unregistered [ 5242.453464] LNet: 316259:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5242.455939] LNetError: 316259:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5242.462888] LNet: Removed LNI 192.168.204.47@tcp [ 5242.672125] Key type .llcrypt unregistered [ 5242.673422] Key type ._llcrypt unregistered [ 5242.938352] Key type ._llcrypt registered [ 5242.940136] Key type .llcrypt registered [ 5243.111270] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5243.116561] alg: No test for adler32 (adler32-zlib) [ 5243.972367] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5244.054081] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5245.640106] Key type lgssc registered [ 5246.001120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5249.291188] Lustre: DEBUG MARKER: Iteration 16 [ 5249.387787] LustreError: 317079:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5249.388034] LustreError: 317080:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5249.393615] LustreError: 317079:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5250.432158] Lustre: Mounted lustre-client [ 5250.433705] Lustre: Skipped 1 previous similar message [ 5250.882942] LustreError: 317181:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a7d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5250.918590] Lustre: Unmounted lustre-client [ 5251.813461] Key type lgssc unregistered [ 5251.925464] LNet: 317423:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5251.928250] LNetError: 317423:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5251.936720] LNet: Removed LNI 192.168.204.47@tcp [ 5252.161459] Key type .llcrypt unregistered [ 5252.162583] Key type ._llcrypt unregistered [ 5252.409874] Key type ._llcrypt registered [ 5252.410890] Key type .llcrypt registered [ 5252.611338] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5252.618292] alg: No test for adler32 (adler32-zlib) [ 5253.476461] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5253.560660] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5255.144133] Key type lgssc registered [ 5255.638572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5259.181983] Lustre: DEBUG MARKER: Iteration 17 [ 5259.284786] LustreError: 318243:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5259.284820] LustreError: 318245:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5259.288978] LustreError: 318243:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5260.335661] Lustre: Mounted lustre-client [ 5260.337198] Lustre: Skipped 1 previous similar message [ 5260.782349] LustreError: 318353:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4d3c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5260.820104] Lustre: Unmounted lustre-client [ 5261.693441] Key type lgssc unregistered [ 5261.804520] LNet: 318593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5261.806732] LNetError: 318593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5261.814698] LNet: Removed LNI 192.168.204.47@tcp [ 5262.014094] Key type .llcrypt unregistered [ 5262.015212] Key type ._llcrypt unregistered [ 5262.257973] Key type ._llcrypt registered [ 5262.259263] Key type .llcrypt registered [ 5262.448204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5262.452955] alg: No test for adler32 (adler32-zlib) [ 5263.299897] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5263.376382] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5264.960118] Key type lgssc registered [ 5265.321050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5268.519402] Lustre: DEBUG MARKER: Iteration 18 [ 5268.610285] LustreError: 319413:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5268.615116] LustreError: 319414:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5268.617264] LustreError: 319413:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5269.668869] Lustre: Mounted lustre-client [ 5269.670704] Lustre: Skipped 1 previous similar message [ 5270.215746] LustreError: 319520:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1fabac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5270.243130] Lustre: Unmounted lustre-client [ 5271.273336] Key type lgssc unregistered [ 5271.397563] LNet: 319762:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5271.401145] LNetError: 319762:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5271.409317] LNet: Removed LNI 192.168.204.47@tcp [ 5271.660104] Key type .llcrypt unregistered [ 5271.661285] Key type ._llcrypt unregistered [ 5271.963161] Key type ._llcrypt registered [ 5271.964189] Key type .llcrypt registered [ 5272.217753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5272.222804] alg: No test for adler32 (adler32-zlib) [ 5273.100403] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5273.190851] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5274.776191] Key type lgssc registered [ 5275.142226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5278.436165] Lustre: DEBUG MARKER: Iteration 19 [ 5278.549799] LustreError: 320583:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5278.551857] LustreError: 320584:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5278.555486] LustreError: 320583:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5279.605387] Lustre: Mounted lustre-client [ 5279.607387] Lustre: Skipped 1 previous similar message [ 5280.068765] LustreError: 320686:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d08d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5280.073684] LustreError: 320686:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5280.087622] Lustre: Unmounted lustre-client [ 5280.941925] Key type lgssc unregistered [ 5281.047500] LNet: 320927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5281.049783] LNetError: 320927:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5281.057809] LNet: Removed LNI 192.168.204.47@tcp [ 5281.275090] Key type .llcrypt unregistered [ 5281.276551] Key type ._llcrypt unregistered [ 5281.512576] Key type ._llcrypt registered [ 5281.513586] Key type .llcrypt registered [ 5281.703205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5281.707902] alg: No test for adler32 (adler32-zlib) [ 5282.553248] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5282.627331] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5284.208099] Key type lgssc registered [ 5284.537379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5287.673730] Lustre: DEBUG MARKER: Iteration 20 [ 5287.779465] LustreError: 321747:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5287.779521] LustreError: 321748:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5287.785596] LustreError: 321747:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5288.835611] Lustre: Mounted lustre-client [ 5288.837946] Lustre: Skipped 1 previous similar message [ 5289.276065] LustreError: 321858:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a7c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5289.280711] LustreError: 321858:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5289.296502] Lustre: Unmounted lustre-client [ 5290.236112] Key type lgssc unregistered [ 5290.343384] LNet: 322099:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5290.346029] LNetError: 322099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5290.354517] LNet: Removed LNI 192.168.204.47@tcp [ 5290.571092] Key type .llcrypt unregistered [ 5290.572293] Key type ._llcrypt unregistered [ 5290.825364] Key type ._llcrypt registered [ 5290.827893] Key type .llcrypt registered [ 5291.007352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5291.012506] alg: No test for adler32 (adler32-zlib) [ 5291.865638] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5291.941489] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5293.520099] Key type lgssc registered [ 5293.868262] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5297.161862] Lustre: DEBUG MARKER: Iteration 21 [ 5297.254990] LustreError: 322920:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5297.255034] LustreError: 322921:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5297.259331] LustreError: 322920:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5298.298486] Lustre: Mounted lustre-client [ 5298.740080] LustreError: 323025:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c6197800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5298.784855] Lustre: Unmounted lustre-client [ 5299.674366] Key type lgssc unregistered [ 5299.781664] LNet: 323269:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5299.784617] LNetError: 323269:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5299.795772] LNet: Removed LNI 192.168.204.47@tcp [ 5300.046133] Key type .llcrypt unregistered [ 5300.048280] Key type ._llcrypt unregistered [ 5300.315094] Key type ._llcrypt registered [ 5300.316810] Key type .llcrypt registered [ 5300.494295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5300.499364] alg: No test for adler32 (adler32-zlib) [ 5301.352495] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5301.434254] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5303.016119] Key type lgssc registered [ 5303.380434] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5306.663486] Lustre: DEBUG MARKER: Iteration 22 [ 5306.770255] LustreError: 324089:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5306.770299] LustreError: 324090:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5306.775139] LustreError: 324089:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5307.819572] Lustre: Mounted lustre-client [ 5307.821129] Lustre: Skipped 1 previous similar message [ 5308.264243] LustreError: 324197:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c32d7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5308.268047] LustreError: 324197:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5308.285762] Lustre: Unmounted lustre-client [ 5309.202820] Key type lgssc unregistered [ 5309.305477] LNet: 324439:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5309.307754] LNetError: 324439:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5309.315688] LNet: Removed LNI 192.168.204.47@tcp [ 5309.520119] Key type .llcrypt unregistered [ 5309.521138] Key type ._llcrypt unregistered [ 5309.747262] Key type ._llcrypt registered [ 5309.748578] Key type .llcrypt registered [ 5309.958735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5309.973165] alg: No test for adler32 (adler32-zlib) [ 5310.840630] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5310.940738] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5312.528097] Key type lgssc registered [ 5312.884561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5316.182341] Lustre: DEBUG MARKER: Iteration 23 [ 5316.286459] LustreError: 325258:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5316.286493] LustreError: 325259:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5316.293345] LustreError: 325258:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5317.340932] Lustre: Mounted lustre-client [ 5317.777195] LustreError: 325367:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cfd0f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5317.781898] LustreError: 325367:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5317.799075] Lustre: Unmounted lustre-client [ 5318.709993] Key type lgssc unregistered [ 5318.817695] LNet: 325610:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5318.822220] LNetError: 325610:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5318.833724] LNet: Removed LNI 192.168.204.47@tcp [ 5319.048105] Key type .llcrypt unregistered [ 5319.049599] Key type ._llcrypt unregistered [ 5319.321941] Key type ._llcrypt registered [ 5319.323054] Key type .llcrypt registered [ 5319.495063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5319.502220] alg: No test for adler32 (adler32-zlib) [ 5320.356869] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5320.444248] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5322.032192] Key type lgssc registered [ 5322.431714] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5325.785481] Lustre: DEBUG MARKER: Iteration 24 [ 5325.888803] LustreError: 326430:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5325.888867] LustreError: 326431:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5325.893766] LustreError: 326430:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5326.932874] Lustre: Mounted lustre-client [ 5327.390695] LustreError: 326532:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c6a20000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5327.438396] Lustre: Unmounted lustre-client [ 5328.380296] Key type lgssc unregistered [ 5328.495496] LNet: 326774:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5328.498530] LNetError: 326774:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5328.507810] LNet: Removed LNI 192.168.204.47@tcp [ 5328.730149] Key type .llcrypt unregistered [ 5328.731858] Key type ._llcrypt unregistered [ 5329.010276] Key type ._llcrypt registered [ 5329.011452] Key type .llcrypt registered [ 5329.210146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5329.216858] alg: No test for adler32 (adler32-zlib) [ 5330.071387] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5330.152421] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5331.744177] Key type lgssc registered [ 5332.125250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5335.521845] Lustre: DEBUG MARKER: Iteration 25 [ 5335.624287] LustreError: 327596:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5335.624334] LustreError: 327597:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5335.630875] LustreError: 327596:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5336.685696] Lustre: Mounted lustre-client [ 5336.686877] Lustre: Skipped 1 previous similar message [ 5337.240820] LustreError: 327705:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d063e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5337.245868] LustreError: 327705:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5337.271976] Lustre: Unmounted lustre-client [ 5338.321980] Key type lgssc unregistered [ 5338.448920] LNet: 327946:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5338.452392] LNetError: 327946:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5338.461578] LNet: Removed LNI 192.168.204.47@tcp [ 5338.722105] Key type .llcrypt unregistered [ 5338.723722] Key type ._llcrypt unregistered [ 5339.014121] Key type ._llcrypt registered [ 5339.022228] Key type .llcrypt registered [ 5339.235058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5339.239797] alg: No test for adler32 (adler32-zlib) [ 5340.087823] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5340.177236] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5341.768179] Key type lgssc registered [ 5342.208767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5345.685913] Lustre: DEBUG MARKER: Iteration 26 [ 5345.808900] LustreError: 328767:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5345.809288] LustreError: 328768:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5345.813467] LustreError: 328767:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5346.868374] Lustre: Mounted lustre-client [ 5346.869522] Lustre: Skipped 1 previous similar message [ 5347.404846] LustreError: 328870:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d07eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5347.409648] LustreError: 328870:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5347.423605] Lustre: Unmounted lustre-client [ 5348.514294] Key type lgssc unregistered [ 5348.642765] LNet: 329114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5348.645301] LNetError: 329114:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5348.658307] LNet: Removed LNI 192.168.204.47@tcp [ 5348.908147] Key type .llcrypt unregistered [ 5348.909416] Key type ._llcrypt unregistered [ 5349.203935] Key type ._llcrypt registered [ 5349.205031] Key type .llcrypt registered [ 5349.450905] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5349.455785] alg: No test for adler32 (adler32-zlib) [ 5350.328608] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5350.418489] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5352.000154] Key type lgssc registered [ 5352.370592] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5355.768876] Lustre: DEBUG MARKER: Iteration 27 [ 5355.866385] LustreError: 329934:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5355.866450] LustreError: 329935:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5355.872265] LustreError: 329934:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5356.922409] Lustre: Mounted lustre-client [ 5357.367846] LustreError: 330041:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1edf73800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5357.372260] LustreError: 330041:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5357.384425] Lustre: Unmounted lustre-client [ 5358.316343] Key type lgssc unregistered [ 5358.432576] LNet: 330284:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5358.437440] LNetError: 330284:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5358.446753] LNet: Removed LNI 192.168.204.47@tcp [ 5358.687109] Key type .llcrypt unregistered [ 5358.688386] Key type ._llcrypt unregistered [ 5358.960948] Key type ._llcrypt registered [ 5358.962835] Key type .llcrypt registered [ 5359.135553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5359.140111] alg: No test for adler32 (adler32-zlib) [ 5360.003919] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5360.085697] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5361.664091] Key type lgssc registered [ 5362.047397] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5365.422820] Lustre: DEBUG MARKER: Iteration 28 [ 5365.515330] LustreError: 331107:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5365.515352] LustreError: 331108:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5365.520209] LustreError: 331107:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5366.566256] Lustre: Mounted lustre-client [ 5366.567928] Lustre: Skipped 1 previous similar message [ 5367.018620] LustreError: 331217:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4afa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5367.023590] LustreError: 331217:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5367.051330] Lustre: Unmounted lustre-client [ 5368.029783] Key type lgssc unregistered [ 5368.146487] LNet: 331460:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5368.149639] LNetError: 331460:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5368.160774] LNet: Removed LNI 192.168.204.47@tcp [ 5368.384123] Key type .llcrypt unregistered [ 5368.385336] Key type ._llcrypt unregistered [ 5368.599705] Key type ._llcrypt registered [ 5368.601351] Key type .llcrypt registered [ 5368.799356] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5368.807055] alg: No test for adler32 (adler32-zlib) [ 5369.663685] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5369.748592] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5371.336172] Key type lgssc registered [ 5371.775285] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5375.250208] Lustre: DEBUG MARKER: Iteration 29 [ 5375.350386] LustreError: 332281:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5375.350432] LustreError: 332280:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5375.355802] LustreError: 332281:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5376.405336] Lustre: Mounted lustre-client [ 5376.406392] Lustre: Skipped 1 previous similar message [ 5376.895818] LustreError: 332388:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1edf77000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5376.899319] LustreError: 332388:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5376.921514] Lustre: Unmounted lustre-client [ 5377.870569] Key type lgssc unregistered [ 5377.983380] LNet: 332629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5377.985794] LNetError: 332629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5377.993722] LNet: Removed LNI 192.168.204.47@tcp [ 5378.216131] Key type .llcrypt unregistered [ 5378.217087] Key type ._llcrypt unregistered [ 5378.604728] Key type ._llcrypt registered [ 5378.610931] Key type .llcrypt registered [ 5378.700141] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5378.705395] alg: No test for adler32 (adler32-zlib) [ 5379.563606] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5379.652127] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5381.240144] Key type lgssc registered [ 5381.614610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5384.943381] Lustre: DEBUG MARKER: Iteration 30 [ 5385.035356] LustreError: 333450:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5385.035750] LustreError: 333452:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5385.040021] LustreError: 333450:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5386.088419] Lustre: Mounted lustre-client [ 5386.523797] LustreError: 333554:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1fedf1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5386.527287] LustreError: 333554:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5386.548082] Lustre: Unmounted lustre-client [ 5387.690882] Key type lgssc unregistered [ 5387.824993] LNet: 333795:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5387.830530] LNetError: 333795:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5387.842222] LNet: Removed LNI 192.168.204.47@tcp [ 5388.204109] Key type .llcrypt unregistered [ 5388.205632] Key type ._llcrypt unregistered [ 5388.494827] Key type ._llcrypt registered [ 5388.495826] Key type .llcrypt registered [ 5388.692203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5388.697191] alg: No test for adler32 (adler32-zlib) [ 5389.550507] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5389.632478] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5391.216169] Key type lgssc registered [ 5391.627486] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5395.138513] Lustre: DEBUG MARKER: Iteration 31 [ 5395.231630] LustreError: 334615:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5395.231967] LustreError: 334616:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5395.235649] LustreError: 334615:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5396.276933] Lustre: Mounted lustre-client [ 5396.278604] Lustre: Skipped 1 previous similar message [ 5396.715986] LustreError: 334725:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c61e7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5396.721868] LustreError: 334725:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5396.729456] Lustre: Unmounted lustre-client [ 5397.870976] Key type lgssc unregistered [ 5397.985893] LNet: 334968:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5397.992554] LNetError: 334968:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5398.007315] LNet: Removed LNI 192.168.204.47@tcp [ 5398.289140] Key type .llcrypt unregistered [ 5398.291458] Key type ._llcrypt unregistered [ 5398.546220] Key type ._llcrypt registered [ 5398.553370] Key type .llcrypt registered [ 5398.749343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5398.754499] alg: No test for adler32 (adler32-zlib) [ 5399.613741] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5399.697473] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5401.280170] Key type lgssc registered [ 5401.770588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5405.100211] Lustre: DEBUG MARKER: Iteration 32 [ 5405.193545] LustreError: 335788:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5405.193576] LustreError: 335790:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5405.198069] LustreError: 335788:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5406.256381] Lustre: Mounted lustre-client [ 5406.257505] Lustre: Skipped 1 previous similar message [ 5406.702989] LustreError: 335898:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d08d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5406.708787] LustreError: 335898:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5406.716418] Lustre: Unmounted lustre-client [ 5407.627309] Key type lgssc unregistered [ 5407.746495] LNet: 336140:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5407.749273] LNetError: 336140:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5407.756431] LNet: Removed LNI 192.168.204.47@tcp [ 5407.978097] Key type .llcrypt unregistered [ 5407.979268] Key type ._llcrypt unregistered [ 5408.245406] Key type ._llcrypt registered [ 5408.247572] Key type .llcrypt registered [ 5408.425869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5408.430839] alg: No test for adler32 (adler32-zlib) [ 5409.285908] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5409.366234] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5410.952177] Key type lgssc registered [ 5411.325054] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5414.591375] Lustre: DEBUG MARKER: Iteration 33 [ 5414.688463] LustreError: 336960:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5414.688512] LustreError: 336961:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5414.694464] LustreError: 336960:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5415.739844] Lustre: Mounted lustre-client [ 5415.741347] Lustre: Skipped 1 previous similar message [ 5416.189516] LustreError: 337063:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c7a78000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5416.222417] Lustre: Unmounted lustre-client [ 5417.150811] Key type lgssc unregistered [ 5417.269422] LNet: 337305:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5417.272823] LNetError: 337305:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5417.280875] LNet: Removed LNI 192.168.204.47@tcp [ 5417.513101] Key type .llcrypt unregistered [ 5417.514430] Key type ._llcrypt unregistered [ 5417.786184] Key type ._llcrypt registered [ 5417.787313] Key type .llcrypt registered [ 5417.968438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5417.973216] alg: No test for adler32 (adler32-zlib) [ 5418.824527] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5418.902508] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5420.480075] Key type lgssc registered [ 5420.851273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5424.174547] Lustre: DEBUG MARKER: Iteration 34 [ 5424.279370] LustreError: 338125:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5424.279645] LustreError: 338126:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5424.285248] LustreError: 338125:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5425.326054] Lustre: Mounted lustre-client [ 5425.327525] Lustre: Skipped 1 previous similar message [ 5425.755928] LustreError: 338233:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4afe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5425.760285] LustreError: 338233:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5425.786091] Lustre: Unmounted lustre-client [ 5426.726088] Key type lgssc unregistered [ 5426.862557] LNet: 338477:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5426.866523] LNetError: 338477:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5426.873877] LNet: Removed LNI 192.168.204.47@tcp [ 5427.132137] Key type .llcrypt unregistered [ 5427.133932] Key type ._llcrypt unregistered [ 5427.429887] Key type ._llcrypt registered [ 5427.431811] Key type .llcrypt registered [ 5427.626409] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5427.631297] alg: No test for adler32 (adler32-zlib) [ 5428.482509] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5428.572217] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5430.152151] Key type lgssc registered [ 5430.492567] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5433.909817] Lustre: DEBUG MARKER: Iteration 35 [ 5434.002668] LustreError: 339297:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5434.002700] LustreError: 339298:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5434.007937] LustreError: 339297:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5435.053287] Lustre: Mounted lustre-client [ 5435.506499] LustreError: 339405:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1fbd51000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5435.511788] LustreError: 339405:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5435.519114] Lustre: Unmounted lustre-client [ 5436.430461] Key type lgssc unregistered [ 5436.546447] LNet: 339647:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5436.549108] LNetError: 339647:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5436.555754] LNet: Removed LNI 192.168.204.47@tcp [ 5436.790115] Key type .llcrypt unregistered [ 5436.791217] Key type ._llcrypt unregistered [ 5437.032858] Key type ._llcrypt registered [ 5437.033827] Key type .llcrypt registered [ 5437.217446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5437.222356] alg: No test for adler32 (adler32-zlib) [ 5438.077664] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5438.164918] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5439.744127] Key type lgssc registered [ 5440.107175] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5443.262387] Lustre: DEBUG MARKER: Iteration 36 [ 5443.365036] LustreError: 340467:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5443.365084] LustreError: 340468:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5443.369455] LustreError: 340467:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5444.414987] Lustre: Mounted lustre-client [ 5444.859886] LustreError: 340575:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cb56c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5444.863397] LustreError: 340575:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5444.876575] Lustre: Unmounted lustre-client [ 5445.803083] Key type lgssc unregistered [ 5445.917508] LNet: 340819:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5445.921125] LNetError: 340819:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5445.932559] LNet: Removed LNI 192.168.204.47@tcp [ 5446.177109] Key type .llcrypt unregistered [ 5446.178946] Key type ._llcrypt unregistered [ 5446.465470] Key type ._llcrypt registered [ 5446.466532] Key type .llcrypt registered [ 5446.641992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5446.646496] alg: No test for adler32 (adler32-zlib) [ 5447.494162] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5447.580435] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5449.168110] Key type lgssc registered [ 5449.500922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5452.669604] Lustre: DEBUG MARKER: Iteration 37 [ 5452.760680] LustreError: 341639:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5452.760740] LustreError: 341640:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5452.767788] LustreError: 341639:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5453.819155] Lustre: Mounted lustre-client [ 5453.820337] Lustre: Skipped 1 previous similar message [ 5454.232945] LustreError: 341747:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c8e49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5454.268072] Lustre: Unmounted lustre-client [ 5455.149736] Key type lgssc unregistered [ 5455.252297] LNet: 341990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5455.254636] LNetError: 341990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5455.260618] LNet: Removed LNI 192.168.204.47@tcp [ 5455.472097] Key type .llcrypt unregistered [ 5455.473169] Key type ._llcrypt unregistered [ 5455.824455] Key type ._llcrypt registered [ 5455.825546] Key type .llcrypt registered [ 5455.914529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5455.919216] alg: No test for adler32 (adler32-zlib) [ 5456.781122] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5456.866522] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5458.448168] Key type lgssc registered [ 5458.821934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5461.989579] Lustre: DEBUG MARKER: Iteration 38 [ 5462.095219] LustreError: 342811:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5462.095272] LustreError: 342812:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5462.099201] LustreError: 342811:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5463.151040] Lustre: Mounted lustre-client [ 5463.152189] Lustre: Skipped 1 previous similar message [ 5463.607549] LustreError: 342917:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c80b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5463.611387] LustreError: 342917:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5463.634663] Lustre: Unmounted lustre-client [ 5464.496306] Key type lgssc unregistered [ 5464.599497] LNet: 343159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5464.601768] LNetError: 343159:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5464.610662] LNet: Removed LNI 192.168.204.47@tcp [ 5464.815101] Key type .llcrypt unregistered [ 5464.816223] Key type ._llcrypt unregistered [ 5465.029793] Key type ._llcrypt registered [ 5465.031237] Key type .llcrypt registered [ 5465.248964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5465.256163] alg: No test for adler32 (adler32-zlib) [ 5466.105909] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5466.191156] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5467.776156] Key type lgssc registered [ 5468.143565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5471.180982] Lustre: DEBUG MARKER: Iteration 39 [ 5471.278848] LustreError: 343979:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5471.278878] LustreError: 343980:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5471.283917] LustreError: 343979:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5472.334894] Lustre: Mounted lustre-client [ 5472.335935] Lustre: Skipped 1 previous similar message [ 5472.766183] LustreError: 344087:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c32d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5472.771696] LustreError: 344087:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5472.786106] Lustre: Unmounted lustre-client [ 5473.672900] Key type lgssc unregistered [ 5473.775441] LNet: 344331:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5473.777603] LNetError: 344331:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5473.783763] LNet: Removed LNI 192.168.204.47@tcp [ 5474.002086] Key type .llcrypt unregistered [ 5474.003214] Key type ._llcrypt unregistered [ 5474.256828] Key type ._llcrypt registered [ 5474.260858] Key type .llcrypt registered [ 5474.471069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5474.477129] alg: No test for adler32 (adler32-zlib) [ 5475.338014] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5475.427918] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5477.008116] Key type lgssc registered [ 5477.344838] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5480.373689] Lustre: DEBUG MARKER: Iteration 40 [ 5480.470552] LustreError: 345151:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5480.470577] LustreError: 345152:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5480.474862] LustreError: 345151:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5481.520604] Lustre: Mounted lustre-client [ 5481.522374] Lustre: Skipped 1 previous similar message [ 5481.958845] LustreError: 345251:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c9fb5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5481.985100] Lustre: Unmounted lustre-client [ 5481.986978] Lustre: Skipped 1 previous similar message [ 5482.784589] Key type lgssc unregistered [ 5482.896429] LNet: 345496:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5482.898802] LNetError: 345496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5482.905821] LNet: Removed LNI 192.168.204.47@tcp [ 5483.123097] Key type .llcrypt unregistered [ 5483.124179] Key type ._llcrypt unregistered [ 5483.380517] Key type ._llcrypt registered [ 5483.387042] Key type .llcrypt registered [ 5483.548898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5483.553639] alg: No test for adler32 (adler32-zlib) [ 5484.409346] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5484.487703] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5486.064115] Key type lgssc registered [ 5486.391221] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5489.363877] Lustre: DEBUG MARKER: Iteration 41 [ 5489.454692] LustreError: 346315:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5489.460531] LustreError: 346317:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5489.463903] LustreError: 346315:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5490.511988] Lustre: Mounted lustre-client [ 5490.513250] Lustre: Skipped 1 previous similar message [ 5490.969056] LustreError: 346424:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c80b7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5490.972529] LustreError: 346424:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5491.007573] Lustre: Unmounted lustre-client [ 5491.897697] Key type lgssc unregistered [ 5492.007769] LNet: 346665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5492.011031] LNetError: 346665:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5492.019488] LNet: Removed LNI 192.168.204.47@tcp [ 5492.254097] Key type .llcrypt unregistered [ 5492.255160] Key type ._llcrypt unregistered [ 5492.552222] Key type ._llcrypt registered [ 5492.553167] Key type .llcrypt registered [ 5492.790515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5492.795247] alg: No test for adler32 (adler32-zlib) [ 5493.651477] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5493.737088] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5495.320129] Key type lgssc registered [ 5495.694698] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5498.937184] Lustre: DEBUG MARKER: Iteration 42 [ 5499.053357] LustreError: 347485:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5499.054521] LustreError: 347486:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5499.057455] LustreError: 347485:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5500.113814] Lustre: Mounted lustre-client [ 5500.115438] Lustre: Skipped 1 previous similar message [ 5500.693491] LustreError: 347592:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4afe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5500.697165] LustreError: 347592:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5500.724684] Lustre: Unmounted lustre-client [ 5501.748788] Key type lgssc unregistered [ 5501.872794] LNet: 347835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5501.875406] LNetError: 347835:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5501.883409] LNet: Removed LNI 192.168.204.47@tcp [ 5502.140143] Key type .llcrypt unregistered [ 5502.141549] Key type ._llcrypt unregistered [ 5502.498569] Key type ._llcrypt registered [ 5502.500205] Key type .llcrypt registered [ 5502.724482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5502.729299] alg: No test for adler32 (adler32-zlib) [ 5503.588216] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5503.667078] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5505.256151] Key type lgssc registered [ 5505.615241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5509.227340] Lustre: DEBUG MARKER: Iteration 43 [ 5509.336860] LustreError: 348656:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5509.339047] LustreError: 348657:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5509.341569] LustreError: 348656:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5510.387073] Lustre: Mounted lustre-client [ 5510.829329] LustreError: 348763:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c28f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5510.832431] LustreError: 348763:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5510.847670] Lustre: Unmounted lustre-client [ 5511.756968] Key type lgssc unregistered [ 5511.863476] LNet: 349005:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5511.865679] LNetError: 349005:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5511.874442] LNet: Removed LNI 192.168.204.47@tcp [ 5512.073087] Key type .llcrypt unregistered [ 5512.074068] Key type ._llcrypt unregistered [ 5512.408047] Key type ._llcrypt registered [ 5512.409262] Key type .llcrypt registered [ 5512.504878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5512.509769] alg: No test for adler32 (adler32-zlib) [ 5513.354259] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5513.428992] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5515.008100] Key type lgssc registered [ 5515.366293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5518.526630] Lustre: DEBUG MARKER: Iteration 44 [ 5518.633602] LustreError: 349826:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5518.633850] LustreError: 349827:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5518.638813] LustreError: 349826:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5519.682657] Lustre: Mounted lustre-client [ 5519.684381] Lustre: Skipped 1 previous similar message [ 5520.114075] LustreError: 349933:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c28f1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5520.119854] LustreError: 349933:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5520.133085] Lustre: Unmounted lustre-client [ 5521.031821] Key type lgssc unregistered [ 5521.143427] LNet: 350175:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5521.145839] LNetError: 350175:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5521.153653] LNet: Removed LNI 192.168.204.47@tcp [ 5521.380098] Key type .llcrypt unregistered [ 5521.381105] Key type ._llcrypt unregistered [ 5521.629495] Key type ._llcrypt registered [ 5521.633599] Key type .llcrypt registered [ 5521.820596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5521.826117] alg: No test for adler32 (adler32-zlib) [ 5522.668668] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5522.741690] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5524.320117] Key type lgssc registered [ 5524.651537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5527.682884] Lustre: DEBUG MARKER: Iteration 45 [ 5527.772367] LustreError: 350995:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5527.772667] LustreError: 350996:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5527.776688] LustreError: 350995:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5528.829863] Lustre: Mounted lustre-client [ 5528.830824] Lustre: Skipped 1 previous similar message [ 5529.274650] LustreError: 351102:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1edf7a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5529.278721] LustreError: 351102:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5529.289537] Lustre: Unmounted lustre-client [ 5530.183791] Key type lgssc unregistered [ 5530.292472] LNet: 351346:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5530.294788] LNetError: 351346:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5530.302650] LNet: Removed LNI 192.168.204.47@tcp [ 5530.525092] Key type .llcrypt unregistered [ 5530.526111] Key type ._llcrypt unregistered [ 5530.765170] Key type ._llcrypt registered [ 5530.766172] Key type .llcrypt registered [ 5530.962081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5530.966730] alg: No test for adler32 (adler32-zlib) [ 5531.829397] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5531.906811] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5533.488153] Key type lgssc registered [ 5533.840601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5537.051111] Lustre: DEBUG MARKER: Iteration 46 [ 5537.145607] LustreError: 352166:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5537.145831] LustreError: 352167:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5537.150341] LustreError: 352166:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5538.205734] Lustre: Mounted lustre-client [ 5538.206751] Lustre: Skipped 1 previous similar message [ 5538.642167] LustreError: 352274:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cea1d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5538.645646] LustreError: 352274:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5538.702127] Lustre: Unmounted lustre-client [ 5539.553154] Key type lgssc unregistered [ 5539.660406] LNet: 352516:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5539.663161] LNetError: 352516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5539.670581] LNet: Removed LNI 192.168.204.47@tcp [ 5539.890110] Key type .llcrypt unregistered [ 5539.891132] Key type ._llcrypt unregistered [ 5540.156690] Key type ._llcrypt registered [ 5540.157755] Key type .llcrypt registered [ 5540.329493] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5540.334080] alg: No test for adler32 (adler32-zlib) [ 5541.178923] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5541.261345] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5542.848128] Key type lgssc registered [ 5543.187612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5546.222678] Lustre: DEBUG MARKER: Iteration 47 [ 5546.314218] LustreError: 353337:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5546.316495] LustreError: 353338:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5546.318599] LustreError: 353337:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5547.360535] Lustre: Mounted lustre-client [ 5547.362096] Lustre: Skipped 1 previous similar message [ 5547.815961] LustreError: 353445:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d07ec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5547.820437] LustreError: 353445:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5547.845091] Lustre: Unmounted lustre-client [ 5548.779857] Key type lgssc unregistered [ 5548.890781] LNet: 353689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5548.893127] LNetError: 353689:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5548.900352] LNet: Removed LNI 192.168.204.47@tcp [ 5549.132093] Key type .llcrypt unregistered [ 5549.133154] Key type ._llcrypt unregistered [ 5549.435226] Key type ._llcrypt registered [ 5549.436229] Key type .llcrypt registered [ 5549.667770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5549.672296] alg: No test for adler32 (adler32-zlib) [ 5550.527765] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5550.620592] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5552.216092] Key type lgssc registered [ 5552.600183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5555.893835] Lustre: DEBUG MARKER: Iteration 48 [ 5556.009819] LustreError: 354510:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5556.011122] LustreError: 354511:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5556.015263] LustreError: 354510:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5557.076401] Lustre: Mounted lustre-client [ 5557.077483] Lustre: Skipped 1 previous similar message [ 5557.632280] LustreError: 354620:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cb992000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5557.635913] LustreError: 354620:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5557.654706] Lustre: Unmounted lustre-client [ 5558.642466] Key type lgssc unregistered [ 5558.763680] LNet: 354863:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5558.766380] LNetError: 354863:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5558.777710] LNet: Removed LNI 192.168.204.47@tcp [ 5559.024132] Key type .llcrypt unregistered [ 5559.025467] Key type ._llcrypt unregistered [ 5559.344563] Key type ._llcrypt registered [ 5559.345580] Key type .llcrypt registered [ 5559.625318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5559.633067] alg: No test for adler32 (adler32-zlib) [ 5560.507966] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5560.603565] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5562.192140] Key type lgssc registered [ 5562.607219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5566.037580] Lustre: DEBUG MARKER: Iteration 49 [ 5566.134034] LustreError: 355683:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5566.135543] LustreError: 355684:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5566.138941] LustreError: 355683:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5567.188971] Lustre: Mounted lustre-client [ 5567.189941] Lustre: Skipped 1 previous similar message [ 5567.754352] LustreError: 355790:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c4d2f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5567.758840] LustreError: 355790:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5567.775303] Lustre: Unmounted lustre-client [ 5568.812611] Key type lgssc unregistered [ 5568.928471] LNet: 356034:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5568.931853] LNetError: 356034:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5568.942238] LNet: Removed LNI 192.168.204.47@tcp [ 5569.187134] Key type .llcrypt unregistered [ 5569.188826] Key type ._llcrypt unregistered [ 5569.487822] Key type ._llcrypt registered [ 5569.488912] Key type .llcrypt registered [ 5569.733717] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5569.741803] alg: No test for adler32 (adler32-zlib) [ 5570.609391] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5570.704949] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5572.288160] Key type lgssc registered [ 5572.745550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5576.386219] Lustre: DEBUG MARKER: Iteration 50 [ 5576.504210] LustreError: 356854:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5576.504257] LustreError: 356855:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5576.509703] LustreError: 356854:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5577.546993] Lustre: Mounted lustre-client [ 5577.990938] LustreError: 356961:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1cea19000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5577.994890] LustreError: 356961:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5578.010076] Lustre: Unmounted lustre-client [ 5578.926590] Key type lgssc unregistered [ 5579.039557] LNet: 357203:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5579.041820] LNetError: 357203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5579.050905] LNet: Removed LNI 192.168.204.47@tcp [ 5579.266102] Key type .llcrypt unregistered [ 5579.267553] Key type ._llcrypt unregistered [ 5579.546939] Key type ._llcrypt registered [ 5579.549910] Key type .llcrypt registered [ 5579.745463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5579.750704] alg: No test for adler32 (adler32-zlib) [ 5580.610158] Lustre: Lustre: Build Version: 2.17.51_75_gd106f95 [ 5580.685558] LNet: Added LNI 192.168.204.47@tcp [8/256/0/180] [ 5582.272105] Key type lgssc registered [ 5582.669988] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5586.740169] Lustre: Mounted lustre-client [ 5589.021292] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 12:11:47 (1775837507) [ 5605.856128] Lustre: 358533:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837508/real 1775837508] req@ffff8ce1ede6ca80 x1862100581360384/t0(0) o36->lustre-MDT0000-mdc-ffff8ce1d07ed800@192.168.204.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1775837524 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5605.866941] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection to lustre-MDT0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5605.878846] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5621.216184] Lustre: 358533:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837524/real 1775837524] req@ffff8ce1ede6ca80 x1862100581360384/t0(0) o36->lustre-MDT0000-mdc-ffff8ce1d07ed800@192.168.204.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1775837540 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5621.227919] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection to lustre-MDT0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5621.236124] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5637.600101] Lustre: 358533:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837540/real 1775837540] req@ffff8ce1ede6ca80 x1862100581360384/t0(0) o36->lustre-MDT0000-mdc-ffff8ce1d07ed800@192.168.204.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1775837556 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5637.608381] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection to lustre-MDT0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5637.617516] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5652.960110] Lustre: 358533:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837556/real 1775837556] req@ffff8ce1ede6ca80 x1862100581360384/t0(0) o36->lustre-MDT0000-mdc-ffff8ce1d07ed800@192.168.204.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1775837572 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5652.971601] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection to lustre-MDT0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5652.982580] Lustre: lustre-MDT0000-mdc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5653.474363] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 12:12:52 (1775837572) [ 5653.954161] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5654.517972] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 12:12:53 (1775837573) [ 5654.986524] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5655.534807] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 12:12:54 (1775837574) [ 5657.707345] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 12:12:56 (1775837576) [ 5658.190513] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5658.750784] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 12:12:57 (1775837577) [ 5659.214924] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5659.773102] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 12:12:58 (1775837578) [ 5660.228812] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5660.768339] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 12:12:59 (1775837579) [ 5660.831288] LustreError: 361186:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 5662.912088] LustreError: 361186:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 5664.900996] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 12:13:03 (1775837583) [ 5682.656116] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837585/real 1775837585] req@ffff8ce1f49a5f80 x1862100581392768/t0(0) o4->lustre-OST0000-osc-ffff8ce1d07ed800@192.168.204.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775837601 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5682.656159] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection to lustre-OST0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5682.665362] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 5682.675077] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5698.016146] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837601/real 1775837601] req@ffff8ce1f49a5f80 x1862100581392768/t0(0) o4->lustre-OST0000-osc-ffff8ce1d07ed800@192.168.204.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775837617 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5698.016188] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection to lustre-OST0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5698.024589] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 5698.032377] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5714.400106] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775837617/real 1775837617] req@ffff8ce1f49a5f80 x1862100581392768/t0(0) o4->lustre-OST0000-osc-ffff8ce1d07ed800@192.168.204.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775837633 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5714.400241] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection to lustre-OST0000 (at 192.168.204.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5714.408942] Lustre: 357396:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5714.419897] Lustre: lustre-OST0000-osc-ffff8ce1d07ed800: Connection restored to 192.168.204.147@tcp (at 192.168.204.147@tcp) [ 5730.194024] Lustre: DEBUG MARKER: oleg447-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8ce1c9f1f800.ost_server_uuid 50 [ 5730.669801] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8ce1c9f1f800.ost_server_uuid in IDLE state after 0 sec [ 5731.173698] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5731.710964] Lustre: DEBUG MARKER: == sanityn test complete, duration 5497 sec ============== 12:14:10 (1775837650) [ 5732.222146] Lustre: DEBUG MARKER: === sanityn: start cleanup 12:14:10 (1775837650) === [ 5773.144276] LustreError: 363177:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1c9f1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5773.156092] Lustre: Unmounted lustre-client [ 5774.303499] Lustre: DEBUG MARKER: === sanityn: finish cleanup 12:14:52 (1775837692) === [ 5774.600972] LustreError: 363477:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8ce1d07ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5774.604440] LustreError: 363477:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5774.624404] Lustre: Unmounted lustre-client [ 5786.177870] Key type lgssc unregistered [ 5786.295483] LNet: 363957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5786.298779] LNetError: 363957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5786.308767] LNet: Removed LNI 192.168.204.47@tcp [ 5786.545100] Key type .llcrypt unregistered [ 5786.546154] Key type ._llcrypt unregistered