[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 431176066 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002209] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.003922] kvm-guest: setup PV IPIs [ 0.005714] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006010] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007004] pid_max: default: 32768 minimum: 301 [ 0.008079] LSM: Security Framework initializing [ 0.009025] Yama: becoming mindful. [ 0.010019] SELinux: Initializing. [ 0.010697] *** VALIDATE selinux *** [ 0.017315] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.020605] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.021080] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.022059] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.023054] *** VALIDATE tmpfs *** [ 0.024314] *** VALIDATE proc *** [ 0.025149] *** VALIDATE cgroup *** [ 0.026004] *** VALIDATE cgroup2 *** [ 0.027191] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.029023] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.030003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.031018] Spectre V2 : User space: Vulnerable [ 0.032003] Speculative Store Bypass: Vulnerable [ 0.034572] debug: unmapping init [mem 0xffffffff84259000-0xffffffff84260fff] [ 0.036000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.036458] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.037010] ... version: 2 [ 0.037781] ... bit width: 48 [ 0.038006] ... generic registers: 4 [ 0.038751] ... value mask: 0000ffffffffffff [ 0.039005] ... max period: 00007fffffffffff [ 0.040005] ... fixed-purpose events: 3 [ 0.040738] ... event mask: 000000070000000f [ 0.041163] rcu: Hierarchical SRCU implementation. [ 0.043097] smp: Bringing up secondary CPUs ... [ 0.044363] x86: Booting SMP configuration: [ 0.045014] .... node #0, CPUs: #1 #2 #3 [ 0.047603] smp: Brought up 1 node, 4 CPUs [ 0.048823] smpboot: Max logical packages: 1 [ 0.049006] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.120087] node 0 deferred pages initialised in 70ms [ 0.122438] devtmpfs: initialized [ 0.123148] x86/mm: Memory block size: 128MB [ 0.125340] gcov: version magic: 0x41383552 [ 0.126603] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.128064] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.130222] pinctrl core: initialized pinctrl subsystem [ 0.131072] [ 0.131363] ************************************************************* [ 0.132006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.133005] ** ** [ 0.135005] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.136005] ** ** [ 0.137006] ** This means that this kernel is built to expose internal ** [ 0.138006] ** IOMMU data structures, which may compromise security on ** [ 0.140005] ** your system. ** [ 0.141004] ** ** [ 0.142005] ** If you see this message and you are not debugging the ** [ 0.143005] ** kernel, report this immediately to your vendor! ** [ 0.144005] ** ** [ 0.146006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.147005] ************************************************************* [ 0.148443] NET: Registered protocol family 16 [ 0.149279] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.151025] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.152025] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.154260] cpuidle: using governor menu [ 0.155343] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.156228] PCI: Using configuration type 1 for base access [ 0.157085] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.164060] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.165008] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.167059] cryptd: max_cpu_qlen set to 1000 [ 0.168214] ACPI: Added _OSI(Module Device) [ 0.169015] ACPI: Added _OSI(Processor Device) [ 0.169852] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.170007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.173456] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.176410] ACPI: Interpreter enabled [ 0.177040] ACPI: PM: (supports S0 S3 S4 S5) [ 0.177925] ACPI: Using IOAPIC for interrupt routing [ 0.179046] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.181235] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.188304] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.190021] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.192009] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.195034] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.199013] acpiphp: Slot [2] registered [ 0.199978] acpiphp: Slot [3] registered [ 0.201045] acpiphp: Slot [4] registered [ 0.201928] acpiphp: Slot [5] registered [ 0.203066] acpiphp: Slot [6] registered [ 0.204061] acpiphp: Slot [7] registered [ 0.205047] acpiphp: Slot [8] registered [ 0.206047] acpiphp: Slot [9] registered [ 0.207087] acpiphp: Slot [10] registered [ 0.207882] acpiphp: Slot [11] registered [ 0.209048] acpiphp: Slot [12] registered [ 0.209944] acpiphp: Slot [13] registered [ 0.210043] acpiphp: Slot [14] registered [ 0.210931] acpiphp: Slot [15] registered [ 0.212043] acpiphp: Slot [16] registered [ 0.212842] acpiphp: Slot [17] registered [ 0.214042] acpiphp: Slot [18] registered [ 0.214844] acpiphp: Slot [19] registered [ 0.215042] acpiphp: Slot [20] registered [ 0.215876] acpiphp: Slot [21] registered [ 0.217053] acpiphp: Slot [22] registered [ 0.217864] acpiphp: Slot [23] registered [ 0.218041] acpiphp: Slot [24] registered [ 0.218823] acpiphp: Slot [25] registered [ 0.220048] acpiphp: Slot [26] registered [ 0.220858] acpiphp: Slot [27] registered [ 0.222041] acpiphp: Slot [28] registered [ 0.222807] acpiphp: Slot [29] registered [ 0.223052] acpiphp: Slot [30] registered [ 0.223972] acpiphp: Slot [31] registered [ 0.225058] PCI host bridge to bus 0000:00 [ 0.225953] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.227009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.229010] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.230008] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.232009] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.233009] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.234102] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.236586] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.239491] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.244388] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.246831] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.248008] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.249007] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.250009] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.252266] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.253465] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.255018] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.256355] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.259007] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.265007] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.268782] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.271676] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.274007] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.277905] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.285012] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.291852] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.294878] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.297008] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.306009] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.312626] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.314199] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.316209] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.317191] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.318122] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.322096] iommu: Default domain type: Passthrough [ 0.323230] SCSI subsystem initialized [ 0.324068] ACPI: bus type USB registered [ 0.324861] usbcore: registered new interface driver usbfs [ 0.326031] usbcore: registered new interface driver hub [ 0.327039] usbcore: registered new device driver usb [ 0.328073] pps_core: LinuxPPS API ver. 1 registered [ 0.328954] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.330016] PTP clock support registered [ 0.331110] EDAC MC: Ver: 3.0.0 [ 0.332440] PCI: Using ACPI for IRQ routing [ 0.333627] NetLabel: Initializing [ 0.334009] NetLabel: domain hash size = 128 [ 0.335008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.336068] NetLabel: unlabeled traffic allowed by default [ 0.338023] vgaarb: loaded [ 0.338748] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.340009] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.344203] clocksource: Switched to clocksource kvm-clock [ 0.422184] VFS: Disk quotas dquot_6.6.0 [ 0.423126] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.424555] *** VALIDATE ramfs *** [ 0.425287] *** VALIDATE hugetlbfs *** [ 0.426113] pnp: PnP ACPI init [ 0.427666] pnp: PnP ACPI: found 6 devices [ 0.441000] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.442843] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.444059] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.445292] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.446694] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.448053] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.449601] NET: Registered protocol family 2 [ 0.451145] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.454288] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.456381] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.459940] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.461857] TCP: Hash tables configured (established 65536 bind 65536) [ 0.463553] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.465402] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.467095] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.468753] NET: Registered protocol family 1 [ 0.470195] RPC: Registered named UNIX socket transport module. [ 0.471259] RPC: Registered udp transport module. [ 0.472128] RPC: Registered tcp transport module. [ 0.472930] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.474092] NET: Registered protocol family 44 [ 0.474830] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.475873] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.476952] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.478079] PCI: CLS 0 bytes, default 64 [ 0.479484] Unpacking initramfs... [ 1.617044] debug: unmapping init [mem 0xffff9eb77cc64000-0xffff9eb77ffcffff] [ 1.619438] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.620675] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.622305] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.040904] Initialise system trusted keyrings [ 2.042807] Key type blacklist registered [ 2.044795] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.051063] zbud: loaded [ 2.052964] *** VALIDATE nfs *** [ 2.053607] *** VALIDATE nfs4 *** [ 2.054473] pstore: using deflate compression [ 2.056129] Platform Keyring initialized [ 2.118508] NET: Registered protocol family 38 [ 2.120388] Key type asymmetric registered [ 2.121900] Asymmetric key parser 'x509' registered [ 2.123773] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.126871] io scheduler mq-deadline registered [ 2.127913] io scheduler kyber registered [ 2.129284] io scheduler bfq registered [ 2.130440] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.132221] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.133885] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.135548] ACPI: Power Button [PWRF] [ 2.199261] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.263658] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.329918] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.357714] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.387132] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.396323] Non-volatile memory driver v1.3 [ 2.397250] Linux agpgart interface v0.103 [ 2.418477] virtio_blk virtio1: [vda] 132696 512-byte logical blocks (67.9 MB/64.8 MiB) [ 2.420907] vda: detected capacity change from 0 to 67940352 [ 2.433644] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.436655] vdb: detected capacity change from 0 to 1073741824 [ 2.442077] libphy: Fixed MDIO Bus: probed [ 2.446894] usbcore: registered new interface driver usbserial_generic [ 2.449617] usbserial: USB Serial support registered for generic [ 2.451386] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.453844] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.454794] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.456338] mousedev: PS/2 mouse device common for all mice [ 2.458878] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.461349] rtc_cmos 00:05: RTC can wake from S4 [ 2.464048] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.464232] rtc_cmos 00:05: registered as rtc0 [ 2.468707] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.471196] intel_pstate: CPU model not supported [ 2.472348] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.476131] hid: raw HID events driver (C) Jiri Kosina [ 2.477958] usbcore: registered new interface driver usbhid [ 2.479208] usbhid: USB HID core driver [ 2.480695] drop_monitor: Initializing network drop monitor service [ 2.482140] Initializing XFRM netlink socket [ 2.483328] NET: Registered protocol family 10 [ 2.485214] Segment Routing with IPv6 [ 2.486070] NET: Registered protocol family 17 [ 2.487188] mpls_gso: MPLS GSO support [ 2.490644] RAS: Correctable Errors collector initialized. [ 2.491718] AVX version of gcm_enc/dec engaged. [ 2.492493] AES CTR mode by8 optimization enabled [ 2.549993] sched_clock: Marking stable (2549961940, 0)->(3150112957, -600151017) [ 2.553124] registered taskstats version 1 [ 2.554887] Loading compiled-in X.509 certificates [ 2.556749] zswap: loaded using pool lzo/zbud [ 2.574804] Key type big_key registered [ 2.586348] Key type encrypted registered [ 2.587294] ima: No TPM chip found, activating TPM-bypass! [ 2.588489] ima: Allocated hash algorithm: sha1 [ 2.589485] ima: No architecture policies found [ 2.590496] evm: Initialising EVM extended attributes: [ 2.591507] evm: security.selinux [ 2.592218] evm: security.ima [ 2.592811] evm: security.capability [ 2.593493] evm: HMAC attrs: 0x1 [ 2.595109] rtc_cmos 00:05: setting system clock to 2025-07-16 17:41:43 UTC (1752687703) [ 2.599098] debug: unmapping init [mem 0xffffffff85203000-0xffffffff853fffff] [ 2.600927] debug: unmapping init [mem 0xffffffff83f82000-0xffffffff84258fff] [ 2.608068] Write protecting the kernel read-only data: 28672k [ 2.610304] debug: unmapping init [mem 0xffffffff82603000-0xffffffff827fffff] [ 2.612033] debug: unmapping init [mem 0xffffffff82f14000-0xffffffff82ffffff] [ 2.637115] 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) [ 2.643745] systemd[1]: Detected virtualization kvm. [ 2.645244] systemd[1]: Detected architecture x86-64. [ 2.646207] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 2.671545] systemd[1]: No hostname configured. [ 2.673109] systemd[1]: Set hostname to . [ 2.674288] random: systemd: uninitialized urandom read (16 bytes read) [ 2.675634] systemd[1]: Initializing machine ID from random generator. [ 2.721969] random: ln: uninitialized urandom read (6 bytes read) [ 2.801625] random: systemd: uninitialized urandom read (16 bytes read) [ 2.804220] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 2.808384] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 2.812577] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Reached target Slices. Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Initrd Root Device. Starting 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. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.413360] device-mapper: uevent: version 1.0.3 [ 3.415050] 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. [ 4.130042] random: fast init done [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.285472] virtio_net virtio0 ens2: renamed from eth0 [ 4.298512] scsi host0: ata_piix [ 4.304411] scsi host1: ata_piix [ 4.305922] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.308299] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.406233] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.104169] random: crng init done [ 9.105317] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.185874] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.175923] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.543784] SELinux: Disabled at runtime. [ 12.611765] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 12.623210] systemd[1]: Detected virtualization kvm. [ 12.624897] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 13.892918] systemd[1]: initrd-switch-root.service: Succeeded. [ 13.897242] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 13.922574] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 13.933092] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 13.942876] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 13.972372] systemd[1]: Starting Journal Service... Starting Journal Service... [ 13.994626] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. Activating swap /dev/disk/by-label/SWAP... Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-getty.slice. [ 14.431607] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Reached target Slices. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 15.223197] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.036732] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 16.092840] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 16.368699] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 16.498618] EDAC sbridge: Ver: 1.1.2 [ 18.143100] Key type dns_resolver registered [ 18.563496] NFS: Registering the id_resolver key type [ 18.565534] Key type id_resolver registered [ 18.567136] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Mark the need to relabel after reboot. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg444-client login: [ 53.516725] libcfs: loading out-of-tree module taints kernel. [ 53.563427] Key type ._llcrypt registered [ 53.584053] Key type .llcrypt registered [ 53.869232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 53.875265] alg: No test for adler32 (adler32-zlib) [ 54.843831] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 55.139758] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 55.142350] LNet: Accept secure, port 988 [ 56.759153] Key type lgssc registered [ 57.273326] Lustre: Echo OBD driver; http://www.lustre.org/ [ 113.927830] Lustre: Mounted lustre-client [ 116.320653] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 129.870583] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing check_logdir /tmp/testlogs/ [ 131.356199] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing yml_node [ 132.976523] Lustre: DEBUG MARKER: Client: 2.16.57.1 [ 133.879170] Lustre: DEBUG MARKER: MDS: 2.16.57.1 [ 134.760440] Lustre: DEBUG MARKER: OSS: 2.16.57.1 [ 135.321353] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Jul 16 13:43:55 EDT 2025 [ 139.743294] Lustre: lustre-OST0000-osc-ffff9eb7d901a800: disconnect after 24s idle [ 141.101643] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 141.579986] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 142.052544] Lustre: DEBUG MARKER: === sanityn: start setup 13:44:02 (1752687842) === [ 142.233817] Lustre: Mounted lustre-client [ 143.350694] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing check_config_client /mnt/lustre [ 149.452852] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 153.350236] Lustre: DEBUG MARKER: === sanityn: finish setup 13:44:13 (1752687853) === [ 153.979895] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 13:44:14 (1752687854) [ 156.495463] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 13:44:16 (1752687856) [ 158.457792] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 13:44:18 (1752687858) [ 160.465361] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 13:44:20 (1752687860) [ 162.513593] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 13:44:22 (1752687862) [ 164.676029] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 13:44:25 (1752687865) [ 166.739710] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 13:44:27 (1752687867) [ 168.924089] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 13:44:29 (1752687869) [ 171.332594] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 13:44:31 (1752687871) [ 173.650560] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 13:44:34 (1752687874) [ 175.802311] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 13:44:36 (1752687876) [ 178.921255] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 13:44:39 (1752687879) [ 181.022217] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 13:44:41 (1752687881) [ 182.975421] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 13:44:43 (1752687883) [ 185.154435] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 13:44:45 (1752687885) [ 187.344083] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 13:44:47 (1752687887) [ 189.637709] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 13:44:50 (1752687890) [ 191.824216] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 13:44:52 (1752687892) [ 194.292112] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 13:44:54 (1752687894) [ 196.531435] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 13:44:56 (1752687896) [ 198.774670] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 13:44:59 (1752687899) [ 198.939130] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 334.007952] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 13:47:14 (1752688034) [ 336.340730] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 13:47:16 (1752688036) [ 338.723822] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 13:47:18 (1752688038) [ 341.393597] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 13:47:21 (1752688041) [ 343.853316] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 13:47:24 (1752688044) [ 346.580814] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 13:47:26 (1752688046) [ 347.413992] Lustre: DEBUG MARKER: chmod [ 350.117839] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 13:47:30 (1752688050) [ 397.527964] hrtimer: interrupt took 7733861 ns [ 902.115451] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 13:56:42 (1752688602) [ 973.793055] Lustre: lustre-OST0001-osc-ffff9eb7c2c49800: disconnect after 23s idle [ 973.796683] Lustre: Skipped 1 previous similar message [ 999.200312] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 13:58:19 (1752688699) [ 1076.347522] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 13:59:36 (1752688776) [ 1171.410138] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 14:01:11 (1752688871) [ 1191.460802] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 14:01:31 (1752688891) [ 1195.390104] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 14:01:35 (1752688895) [ 1195.919249] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1195.952768] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1195.989486] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.023741] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.055842] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.094946] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.128629] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.157700] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.194339] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.229736] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.264825] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.299957] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.340401] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.375864] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.403832] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.441845] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.472135] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.521858] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.564718] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.600388] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.641155] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.689151] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.727435] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.760521] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.807333] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.841421] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.878901] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.918373] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1196.952775] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.000800] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.042982] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.088806] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.131934] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.173345] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.214087] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.258398] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.293941] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.338495] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.388188] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.430214] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.475481] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.512337] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.544167] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.578942] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.619811] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.662802] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.703714] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.748256] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.790729] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.823923] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.851159] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.886428] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.914727] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.943479] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1197.971837] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.000869] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.033721] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.064189] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.101358] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.139821] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.173506] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.203375] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.238394] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.272427] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.303239] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.340560] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.382082] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.411991] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.448570] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.484541] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.521914] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.556399] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.592315] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.629710] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.657606] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.694557] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.733767] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.777204] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.813792] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.845757] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.885433] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.921173] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.955325] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1198.995275] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.033792] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.069666] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.102343] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.136692] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.175857] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.213833] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.246753] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.274220] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.302971] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.333661] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.370325] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.409439] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.447559] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.482255] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.516391] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.551313] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.589517] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.629452] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.665478] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.695568] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.736928] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.776222] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.825957] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.866147] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.910350] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1199.952203] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.009197] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.052206] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.091309] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.135793] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.168595] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.204306] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.248978] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.293287] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.324117] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.357582] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.390788] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.420281] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.452482] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.484294] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.527878] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.573838] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.625403] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.659001] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.692072] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.743554] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.786624] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.825801] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.861943] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.891566] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.924101] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.959494] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1200.997983] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.029289] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.061135] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.093203] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.131528] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.174692] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.212980] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.246385] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.276622] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.309102] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.342163] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.376815] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.408431] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.450845] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.492260] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.530347] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.572390] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.614282] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.660804] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.702210] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.744808] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.792142] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.827935] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.857797] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.892149] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.941939] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1201.979591] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.012808] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.056890] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.101380] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.143385] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.194230] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.235795] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.267810] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.301061] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.342586] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.383285] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.423759] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.465542] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.510171] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.551325] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.588479] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.626190] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.668620] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.717394] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.760152] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.792305] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.836368] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.885863] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.927337] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1202.971104] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.005712] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.037682] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.072928] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.110994] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.140855] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.179812] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.219276] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.251383] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.302398] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.342108] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.385853] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.424139] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.464385] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.510722] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.545454] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.572634] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.606260] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.651302] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.696782] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.748461] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.795832] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.834617] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.873417] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.934236] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1203.977461] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.012766] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.050561] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.081056] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.121892] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.159453] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.199478] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.242721] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.282794] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.320148] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.356081] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.390378] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.430055] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.474850] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.520413] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.556819] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.594231] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.629359] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.657088] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.692486] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.724543] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.759404] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.802191] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.843188] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.882302] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.924186] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.953077] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1204.980672] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.015959] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.047754] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.090983] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.127413] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.157833] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.201816] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.237845] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.282225] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.323098] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.364233] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.395469] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.425898] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.456513] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.487831] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.530644] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.560831] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.594511] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.640269] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.689187] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.729052] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.778774] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.808407] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.839550] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.874725] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.904961] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.944967] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1205.994751] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.039702] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.074418] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.110216] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.160307] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.195701] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.225812] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.256397] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.290243] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.321577] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.355633] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.389168] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.422391] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.459615] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.491687] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.523258] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.563706] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.603495] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.638449] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.672079] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.707484] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.736519] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.773466] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.806625] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.846475] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.877936] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.917788] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.945873] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1206.976290] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.007948] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.038426] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.071397] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.100910] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.131128] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.163656] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.209070] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.248730] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.290365] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.331237] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.376227] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.407247] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.445620] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.480255] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.521655] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.559575] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.599401] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.635612] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.668489] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.701218] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.744811] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.776868] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.815315] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.858712] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.889254] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.918711] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.954064] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1207.987466] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.021503] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.053127] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.087821] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.123055] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.153626] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.189166] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.229265] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.258310] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.286924] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.320786] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.349758] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.378728] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.414763] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.444746] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.475075] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.515854] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.549906] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.583911] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.612300] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.647959] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.680609] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.711530] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.740884] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.774908] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.813340] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.842748] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.876551] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.915835] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.953353] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1208.985475] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.012559] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.047059] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.076858] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.110620] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.139845] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.171197] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.206353] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.239056] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.270278] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.310143] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.352578] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.385559] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.419760] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.450457] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.479826] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.510105] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.539640] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.570162] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.603184] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.636688] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.670096] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.701227] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.737705] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.774680] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.810969] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.848101] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.885369] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.931807] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1209.983685] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.028313] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.066739] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.100315] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.149792] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.196397] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.239324] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.277996] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.326930] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.364129] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.394154] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.444372] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.494960] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.545298] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.582804] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.620224] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.664896] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.700124] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.737736] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.770414] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.806420] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.843827] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.886290] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.922236] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.960526] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1210.996542] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.029559] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.063522] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.098801] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.136139] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.175722] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.210378] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.246729] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.283500] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.321787] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.370841] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.417641] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.457339] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.494307] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.540590] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.583824] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.626366] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.657156] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.695918] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.732959] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.763892] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.795106] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.843957] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.892509] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.936597] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1211.992371] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.034959] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.078879] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.136686] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.180509] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.219725] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.253936] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.296213] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.332496] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.375672] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.427136] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.467838] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.510415] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.541107] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.583197] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.621251] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.655398] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.707836] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.763170] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.821190] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.868735] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.919604] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.952600] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1212.997224] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.039848] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.084105] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.123305] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.170059] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.210470] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.247089] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.281133] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.317167] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.359561] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.394075] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.437279] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.468622] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.495817] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.530240] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.563313] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.596551] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.630915] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.664841] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.692200] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.727955] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.757220] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.786875] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.817895] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.846485] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.885271] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.910387] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.940574] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1213.978727] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.015979] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.052583] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.087374] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.116834] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.152887] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.184817] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.221888] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.259845] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.296375] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.327866] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.359930] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.391059] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.432228] Lustre: lustre-OST0000-osc-ffff9eb7d901a800: disconnect after 20s idle [ 1214.432232] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.465747] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.496715] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.527538] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.563844] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.597606] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.638086] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.673579] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.711714] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.743219] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.776616] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.810362] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.841891] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.871110] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.908882] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.949708] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1214.988714] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.030828] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.071957] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.101382] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.132056] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.170081] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.207074] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.242714] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.272889] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.302480] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.342461] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.384404] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.417245] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.446813] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1215.485120] rw_seq_cst_vs_d (32441): drop_caches: 3 [ 1219.457962] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 14:01:59 (1752688919) [ 1219.804397] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1219.843772] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1219.979326] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.010755] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.075305] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.115268] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.207265] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.255607] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.373080] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.451986] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.581186] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.647831] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.747466] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.779347] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.881868] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1220.935119] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.036478] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.089343] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.221766] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.246298] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.377645] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.454898] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.496375] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.594778] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.637283] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.659781] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.736573] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.787082] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.911863] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1221.936256] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.025458] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.120754] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.147614] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.255449] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.288430] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.318780] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.379300] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.674052] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.818799] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.846976] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1222.880358] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.050614] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.148122] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.250043] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.324779] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.353531] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.452894] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.519034] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.675419] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.768186] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.854532] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.886427] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1223.913595] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1224.011096] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1224.127676] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1224.161371] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1224.488880] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1224.754598] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.126748] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.174313] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.204953] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.232669] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.257241] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.296658] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.447289] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.561447] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.677134] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.729141] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.801244] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1225.969337] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.008223] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.069380] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.124449] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.200969] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.229545] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.330127] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.448641] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.473705] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.676849] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.860335] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.908886] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1226.970660] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1227.225303] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1227.676198] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1227.800878] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1227.844170] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.106866] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.211978] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.234144] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.395554] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.580162] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1228.699204] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.421981] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.528709] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.558590] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.584832] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.614081] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.636751] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.659647] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.769245] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1229.951371] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.013832] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.136766] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.224786] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.248575] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.321645] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.438720] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.471428] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.811875] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1230.834056] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.003557] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.032479] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.065431] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.094804] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.126352] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.150658] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.229021] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.457062] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.526905] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.557847] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.606865] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1231.849530] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.004346] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.223067] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.285246] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.364261] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.537542] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.602659] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.644636] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.691329] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.713175] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.795139] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.883344] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.950635] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1232.999221] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.049179] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.077522] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.144825] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.326591] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.352638] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.379269] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.495283] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.628997] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.723091] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.921613] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1233.949746] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1234.628319] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1234.829682] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.174280] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.210567] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.295351] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.378555] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.427510] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.456742] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.632243] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.658653] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.686172] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1235.714960] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.052381] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.081572] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.103639] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.127883] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.323434] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.349878] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.369912] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.400152] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.417503] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.689962] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1236.725709] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.243949] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.273826] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.292476] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.408885] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.438895] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.458927] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.479271] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.513728] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.534957] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.759130] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.782668] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.901537] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1237.959619] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.066508] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.281558] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.309969] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.360159] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.396597] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1238.547855] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.049357] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.080100] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.119741] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.283852] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.310155] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1239.334822] rw_seq_cst_vs_d (33028): drop_caches: 3 [ 1240.031471] Lustre: lustre-OST0001-osc-ffff9eb7c2c49800: disconnect after 24s idle [ 1240.038688] Lustre: Skipped 1 previous similar message [ 1243.192673] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 14:02:23 (1752688943) [ 1246.958442] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 14:02:27 (1752688947) [ 1250.158920] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 14:02:30 (1752688950) [ 1265.490555] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 14:02:45 (1752688965) [ 1265.687990] bash (35503): drop_caches: 3 [ 1268.778163] bash (35503): drop_caches: 3 [ 1271.864450] bash (35503): drop_caches: 3 [ 1275.062906] bash (35503): drop_caches: 3 [ 1278.145783] bash (35503): drop_caches: 3 [ 1281.975257] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 14:03:02 (1752688982) [ 1287.643268] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 14:03:07 (1752688987) [ 1308.555615] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 14:03:28 (1752689008) [ 1312.499981] Lustre: DEBUG MARKER: loop 5 [ 1314.524795] Lustre: DEBUG MARKER: loop 10 [ 1316.681516] Lustre: DEBUG MARKER: loop 15 [ 1318.746651] Lustre: DEBUG MARKER: loop 20 [ 1321.866704] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 14:03:42 (1752689022) [ 1324.557328] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 14:03:44 (1752689024) [ 1327.266522] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 14:03:47 (1752689027) [ 1342.431226] Lustre: lustre-OST0000-osc-ffff9eb7d901a800: disconnect after 21s idle [ 1347.551375] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 20s idle [ 1347.554783] Lustre: Skipped 2 previous similar messages [ 1391.642637] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 14:04:51 (1752689091) [ 1394.383825] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 14:04:54 (1752689094) [ 1397.124173] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 14:04:57 (1752689097) [ 1400.123920] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 14:05:00 (1752689100) [ 1403.025283] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 14:05:03 (1752689103) [ 1406.592894] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 14:05:06 (1752689106) [ 1411.126471] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 14:05:11 (1752689111) [ 1414.490354] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1415.091316] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 14:05:15 (1752689115) [ 1419.531367] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 14:05:19 (1752689119) [ 1419.698730] Lustre: *** cfs_fail_loc=314, val=0*** [ 1420.767195] Lustre: *** cfs_fail_loc=314, val=0*** [ 1420.768859] Lustre: Skipped 2 previous similar messages [ 1422.980183] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 14:05:23 (1752689123) [ 1429.766637] Lustre: *** cfs_fail_loc=314, val=0*** [ 1432.239740] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 14:05:32 (1752689132) [ 1432.341526] LustreError: 47102:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1433.359617] LustreError: lustre-OST0000-osc-ffff9eb7c2c49800: operation ldlm_enqueue to node 192.168.204.144@tcp failed: rc = -107 [ 1433.364835] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1433.374360] LustreError: lustre-OST0000-osc-ffff9eb7c2c49800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1433.379377] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1435.359121] LustreError: 47102:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1437.431205] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 14:05:37 (1752689137) [ 1440.203519] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 14:05:40 (1752689140) [ 1443.382923] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1443.976139] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 14:05:44 (1752689144) [ 1444.606204] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1445.230191] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 14:05:45 (1752689145) [ 1449.952985] LustreError: MGC192.168.204.144@tcp: Connection to MGS (at 192.168.204.144@tcp) was lost; in progress operations using this service will fail [ 1449.953026] Lustre: lustre-MDT0000-mdc-ffff9eb7d901a800: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1449.959962] Lustre: Evicted from MGS (at 192.168.204.144@tcp) after server handle changed from 0xb15c94a495298767 to 0xb15c94a495537500 [ 1449.968953] Lustre: MGC192.168.204.144@tcp: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1449.985559] LustreError: 2413:0:(client.c:3395:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@ffff9eb7c9725500 x1837826351368576/t4295011063(4295011063) o101->lustre-MDT0000-mdc-ffff9eb7d901a800@192.168.204.144@tcp:12/10 lens 576/608 e 0 to 0 dl 1752689166 ref 2 fl Interpret:RPQU/604/0 rc 301/301 job:'cat.0' uid:0 gid:0 projid:0 [ 1454.935554] Lustre: lustre-MDT0000-mdc-ffff9eb7c2c49800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1455.071289] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: disconnect after 21s idle [ 1465.235613] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 14:06:05 (1752689165) [ 1482.512757] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 14:06:22 (1752689182) [ 1489.757187] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 14:06:30 (1752689190) [ 1541.046607] Lustre: lustre-OST0000-osc-ffff9eb7d901a800: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1541.051131] Lustre: Skipped 1 previous similar message [ 1541.054363] LustreError: lustre-OST0000-osc-ffff9eb7c2c49800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1541.057493] LustreError: lustre-OST0000-osc-ffff9eb7d901a800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1541.057673] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1541.062430] Lustre: Skipped 2 previous similar messages [ 1551.286528] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: Connection to lustre-OST0001 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1551.290273] Lustre: Skipped 1 previous similar message [ 1551.293206] LustreError: lustre-OST0001-osc-ffff9eb7d901a800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1551.299318] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 1562.591259] Lustre: lustre-OST0000-osc-ffff9eb7d901a800: disconnect after 22s idle [ 1565.779687] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1566.368930] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid in IDLE state after 0 sec [ 1567.735153] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1568.315033] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid in FULL state after 0 sec [ 1570.267253] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1570.839254] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid in IDLE state after 0 sec [ 1572.241919] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1572.843418] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid in FULL state after 0 sec [ 1576.659861] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1577.298988] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9eb7c2c49800.ost_server_uuid in IDLE state after 0 sec [ 1578.772549] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid 50 [ 1579.370192] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9eb7c2c49800.ost_server_uuid in FULL state after 0 sec [ 1580.020181] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 14:08:00 (1752689280) [ 1581.046185] Lustre: DEBUG MARKER: Race attempt 0 [ 1582.711285] Lustre: DEBUG MARKER: Wait for 57666 57792 for 60 sec... [ 1645.565600] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 14:09:05 (1752689345) [ 1651.339608] Lustre: DEBUG MARKER: start test - cycle (0) [ 1667.080467] Lustre: DEBUG MARKER: start test - cycle (1) [ 1686.138982] Lustre: DEBUG MARKER: start test - cycle (2) [ 1701.792549] Lustre: DEBUG MARKER: start test - cycle (3) [ 1717.511907] Lustre: DEBUG MARKER: start test - cycle (4) [ 1733.266707] Lustre: DEBUG MARKER: start test - cycle (5) [ 1748.506665] Lustre: DEBUG MARKER: start test - cycle (6) [ 1763.974789] Lustre: DEBUG MARKER: start test - cycle (7) [ 1779.504799] Lustre: DEBUG MARKER: start test - cycle (8) [ 1794.984140] Lustre: DEBUG MARKER: start test - cycle (9) [ 1809.977957] Lustre: DEBUG MARKER: start test - cycle (10) [ 1827.748995] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 14:12:08 (1752689528) [ 1839.071304] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: disconnect after 22s idle [ 1839.077304] Lustre: Skipped 2 previous similar messages [ 1851.325950] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 14:12:31 (1752689551) [ 1854.138684] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 14:12:34 (1752689554) [ 1857.823434] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 14:12:38 (1752689558) [ 1861.575632] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 14:12:41 (1752689561) [ 1861.661675] Lustre: *** cfs_fail_loc=411, val=0*** [ 1864.172321] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1864.884459] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 14:12:45 (1752689565) [ 1874.464646] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 14:12:54 (1752689574) [ 1883.222094] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 14:13:03 (1752689583) [ 1892.173841] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 14:13:12 (1752689592) [ 1900.588682] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 14:13:20 (1752689600) [ 1906.594568] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 14:13:26 (1752689606) [ 1912.492947] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 14:13:32 (1752689612) [ 1918.684853] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 14:13:39 (1752689619) [ 1924.620977] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 14:13:45 (1752689625) [ 1930.932115] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 14:13:51 (1752689631) [ 1937.419101] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 14:13:57 (1752689637) [ 1943.698361] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 14:14:04 (1752689644) [ 1951.036038] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 14:14:11 (1752689651) [ 1967.071230] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 21s idle [ 1967.073755] Lustre: Skipped 2 previous similar messages [ 2571.232244] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: disconnect after 24s idle [ 2571.234751] Lustre: Skipped 1 previous similar message [ 2702.801081] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 14:26:43 (1752690403) [ 2708.451636] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 14:26:48 (1752690408) [ 2714.044815] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 14:26:54 (1752690414) [ 2719.962325] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 14:27:00 (1752690420) [ 2725.781705] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 14:27:06 (1752690426) [ 2731.810780] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 14:27:12 (1752690432) [ 2737.685677] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 14:27:18 (1752690438) [ 2743.439402] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 14:27:23 (1752690443) [ 2749.359754] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 14:27:29 (1752690449) [ 2781.122665] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 14:28:01 (1752690481) [ 2786.794921] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 14:28:07 (1752690487) [ 2792.297833] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 14:28:12 (1752690492) [ 2797.657400] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 14:28:18 (1752690498) [ 2803.084866] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 14:28:23 (1752690503) [ 2808.440844] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 14:28:28 (1752690508) [ 2813.922761] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 14:28:34 (1752690514) [ 2819.238504] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 14:28:39 (1752690519) [ 2824.643424] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 14:28:45 (1752690525) [ 2837.471264] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 21s idle [ 2837.474036] Lustre: Skipped 3 previous similar messages [ 2872.343947] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 14:29:32 (1752690572) [ 3368.891251] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 14:37:49 (1752691069) [ 3374.585878] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 14:37:54 (1752691074) [ 3380.173144] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 14:38:00 (1752691080) [ 3385.803632] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 14:38:06 (1752691086) [ 3390.967081] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 14:38:11 (1752691091) [ 3396.047271] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 14:38:16 (1752691096) [ 3401.095437] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 14:38:21 (1752691101) [ 3406.143405] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 14:38:26 (1752691106) [ 3411.249448] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 14:38:31 (1752691111) [ 3416.589291] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 14:38:36 (1752691116) [ 3431.392175] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 20s idle [ 3431.395194] Lustre: Skipped 2 previous similar messages [ 3456.295835] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 14:39:16 (1752691156) [ 3461.278121] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 14:39:21 (1752691161) [ 3466.324812] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 14:39:26 (1752691166) [ 3471.365806] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 14:39:31 (1752691171) [ 3476.857852] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 14:39:37 (1752691177) [ 3482.433886] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 14:39:42 (1752691182) [ 3488.006765] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 14:39:48 (1752691188) [ 3493.084206] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 14:39:53 (1752691193) [ 3498.669884] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 14:39:59 (1752691199) [ 3984.483334] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 14:48:04 (1752691684) [ 3989.884568] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 14:48:10 (1752691690) [ 3995.199710] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 14:48:15 (1752691695) [ 4000.567031] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 14:48:20 (1752691700) [ 4005.731175] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 14:48:26 (1752691706) [ 4010.848293] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 14:48:31 (1752691711) [ 4016.214418] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 14:48:36 (1752691716) [ 4021.656709] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 14:48:42 (1752691722) [ 4027.111508] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 14:48:47 (1752691727) [ 4032.538763] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 14:48:52 (1752691732) [ 4037.897351] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 14:48:58 (1752691738) [ 4044.228899] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 14:49:04 (1752691744) [ 4049.553748] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 14:49:10 (1752691750) [ 4054.834096] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 14:49:15 (1752691755) [ 4060.025376] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 14:49:20 (1752691760) [ 4065.210470] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 14:49:25 (1752691765) [ 4071.288280] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 14:49:31 (1752691771) [ 4071.370462] LustreError: 22657:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4073.455088] LustreError: 22657:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4078.492734] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 14:49:38 (1752691778) [ 4082.603453] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 14:49:43 (1752691783) [ 4082.676424] LustreError: 285540:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4086.735121] LustreError: 285540:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4086.741236] LustreError: 285540:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4090.799124] LustreError: 285540:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4090.813573] LustreError: 285547:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4094.871087] LustreError: 285547:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4096.934811] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 14:49:57 (1752691797) [ 4103.586121] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 14:50:04 (1752691804) [ 4106.733160] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 14:50:07 (1752691807) [ 4110.810290] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 14:50:11 (1752691811) [ 4117.471217] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 21s idle [ 4117.473488] Lustre: Skipped 1 previous similar message [ 4134.829886] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 14:50:35 (1752691835) [ 4142.379136] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 14:50:42 (1752691842) [ 4149.784508] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 14:50:50 (1752691850) [ 4162.484127] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 14:51:02 (1752691862) [ 4171.899376] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 14:51:12 (1752691872) [ 4184.787676] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 14:51:25 (1752691885) [ 4197.664393] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 14:51:38 (1752691898) [ 4211.470101] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 14:51:51 (1752691911) [ 4218.378221] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 14:51:58 (1752691918) [ 4246.736522] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 14:52:27 (1752691947) [ 4248.935374] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4251.325120] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 14:52:31 (1752691951) [ 4254.063689] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 14:52:34 (1752691954) [ 4256.374183] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 14:52:36 (1752691956) [ 4258.466987] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 14:52:38 (1752691958) [ 4268.443681] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 14:52:48 (1752691968) [ 4282.884122] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 14:53:03 (1752691983) [ 4285.099442] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 14:53:05 (1752691985) [ 4287.307696] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 14:53:07 (1752691987) [ 4290.370292] LustreError: lustre-MDT0000-mdc-ffff9eb7c2c49800: operation ldlm_enqueue to node 192.168.204.144@tcp failed: rc = -35 [ 4290.372959] LustreError: Skipped 1 previous similar message [ 4293.433187] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 14:53:13 (1752691993) [ 4293.575264] LustreError: 2417:0:(osc_request.c:3088:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4295.655163] LustreError: 2417:0:(osc_request.c:3088:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4300.533366] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 14:53:20 (1752692000) [ 4346.889945] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 14:54:07 (1752692047) [ 4350.235478] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 14:54:10 (1752692050) [ 4354.485802] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 14:54:14 (1752692054) [ 4359.755742] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 14:54:20 (1752692060) [ 4365.065504] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 14:54:25 (1752692065) [ 4372.618516] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 14:54:33 (1752692073) [ 4380.591457] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 14:54:40 (1752692080) [ 4384.061199] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 14:54:44 (1752692084) [ 4388.055577] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 14:54:48 (1752692088) [ 4395.988715] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 14:54:56 (1752692096) [ 4436.438690] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 14:55:36 (1752692136) [ 4550.133922] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 14:57:30 (1752692250) [ 4667.098592] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 14:59:27 (1752692367) [ 4762.591253] Lustre: lustre-OST0001-osc-ffff9eb7d901a800: disconnect after 23s idle [ 4762.594214] Lustre: Skipped 10 previous similar messages [ 4934.971976] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 15:03:55 (1752692635) [ 4938.359133] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 15:03:58 (1752692638) [ 4989.047349] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 15:04:49 (1752692689) [ 5036.266366] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 15:05:36 (1752692736) [ 5040.039784] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 15:05:40 (1752692740) [ 5078.928986] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 15:06:19 (1752692779) [ 5091.508570] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 15:06:31 (1752692791) [ 5134.339635] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 15:07:14 (1752692834) [ 5137.324903] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 15:07:17 (1752692837) [ 5150.095249] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 15:07:30 (1752692850) [ 5153.749234] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 15:07:34 (1752692854) [ 5154.191486] LustreError: 340869:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xcb9:0x0]: rc = -5 [ 5154.196327] LustreError: 340869:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5154.776880] LustreError: 340913:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x99a:0x0]: rc = -5 [ 5154.779934] LustreError: 340913:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 3 previous similar messages [ 5154.783151] LustreError: 340913:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5154.785588] LustreError: 340913:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 3 previous similar messages [ 5155.842528] LustreError: 340996:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9bd:0x0]: rc = -5 [ 5155.846322] LustreError: 340996:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 22 previous similar messages [ 5155.851156] LustreError: 340996:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5155.855090] LustreError: 340996:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 22 previous similar messages [ 5157.905373] LustreError: 341171:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0xa0a:0x0]: rc = -5 [ 5157.909710] LustreError: 341171:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 50 previous similar messages [ 5157.912238] LustreError: 341171:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5157.914448] LustreError: 341171:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 50 previous similar messages [ 5162.010295] LustreError: 341502:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0xa7f:0x0]: rc = -5 [ 5162.013353] LustreError: 341502:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 119 previous similar messages [ 5162.016085] LustreError: 341502:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5162.018369] LustreError: 341502:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 119 previous similar messages [ 5267.801511] LustreError: 341800:0:(lcommon_cl.c:188:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xdb6:0x0]: rc = -5 [ 5267.805851] LustreError: 341800:0:(lcommon_cl.c:188:cl_file_inode_init()) Skipped 112 previous similar messages [ 5267.806450] LustreError: lustre-MDT0000-mdc-ffff9eb7d901a800: operation mds_getattr_lock to node 192.168.204.144@tcp failed: rc = -107 [ 5267.810503] LustreError: 341800:0:(llite_lib.c:3770:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5267.813859] Lustre: lustre-MDT0000-mdc-ffff9eb7d901a800: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5267.815263] LustreError: lustre-MDT0000-mdc-ffff9eb7d901a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 5267.818266] LustreError: 341800:0:(llite_lib.c:3770:ll_prep_inode()) Skipped 112 previous similar messages [ 5267.825465] LustreError: 341796:0:(file.c:248:ll_close_inode_openhandle()) lustre-clilmv-ffff9eb7d901a800: inode [0x200000402:0xdb4:0x0] mdc close failed: rc = -108 [ 5267.830625] Lustre: lustre-MDT0000-mdc-ffff9eb7d901a800: Connection restored to 192.168.204.144@tcp (at 192.168.204.144@tcp) [ 5270.130578] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 15:09:30 (1752692970) [ 5272.370541] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 15:09:32 (1752692972) [ 5317.527540] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 15:10:17 (1752693017) [ 5318.032600] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5318.586058] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 15:10:19 (1752693019) [ 5359.104285] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 15:10:59 (1752693059) [ 5361.172211] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 15:11:01 (1752693061) [ 5387.232242] Lustre: lustre-OST0000-osc-ffff9eb7c2c49800: disconnect after 24s idle [ 5387.234477] Lustre: Skipped 6 previous similar messages [ 5483.433450] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 15:13:03 (1752693183) [ 5490.707774] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 15:13:11 (1752693191) [ 5493.388284] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 15:13:13 (1752693193) [ 5675.942065] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 15:16:16 (1752693376) [ 5859.252251] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 15:19:19 (1752693559) [ 5862.050800] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 15:19:22 (1752693562) [ 5871.239252] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 15:19:31 (1752693571) [ 5871.296851] Lustre: DEBUG MARKER: write [ 5871.312080] LustreError: 6132:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5873.315819] Lustre: DEBUG MARKER: kill 400019 [ 5873.317507] LustreError: 400019:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5876.407109] LustreError: 6132:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5879.351103] LustreError: 400019:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5881.885743] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 15:19:42 (1752693582) [ 5884.052407] LustreError: 400633:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5894.143172] LustreError: 400633:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5897.211926] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 15:19:57 (1752693597) [ 5897.311210] LustreError: 401220:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5899.391154] LustreError: 401220:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 5905.606343] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 15:20:06 (1752693606) [ 5906.117480] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5906.737808] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 15:20:07 (1752693607) [ 5909.266044] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 15:20:09 (1752693609) [ 5911.635758] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 15:20:12 (1752693612) [ 5913.638587] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 15:20:14 (1752693614) [ 5915.633804] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 15:20:16 (1752693616) [ 5917.825848] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 15:20:18 (1752693618) [ 5919.946914] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 15:20:20 (1752693620) [ 5923.305151] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 15:20:23 (1752693623) [ 5926.198398] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 15:20:26 (1752693626) [ 5926.839497] Lustre: *** cfs_fail_loc=415, val=0*** [ 5933.543395] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 15:20:33 (1752693633) [ 5952.606490] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 15:20:53 (1752693653) [ 5952.718906] LustreError: 6606:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5952.721095] LustreError: 6606:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 5957.719114] LustreError: 6606:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5967.919117] LustreError: 6606:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5967.923263] LustreError: 6606:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 6 previous similar messages [ 5975.245186] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 15:21:15 (1752693675) [ 5977.709777] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 15:21:18 (1752693678) [ 5980.144690] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 15:21:20 (1752693680) [ 5982.262963] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 15:21:22 (1752693682) [ 5986.551161] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 15:21:26 (1752693686) [ 5995.144784] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 15:21:35 (1752693695) [ 5995.296259] LustreError: 411947:0:(osc_request.c:2939:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5995.298437] LustreError: 411947:0:(osc_request.c:2939:osc_build_rpc()) Skipped 6 previous similar messages [ 5999.359096] LustreError: 411947:0:(osc_request.c:2939:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 5999.361867] LustreError: 411947:0:(osc_request.c:2939:osc_build_rpc()) Skipped 2 previous similar messages [ 6002.202745] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 15:21:42 (1752693702) [ 6003.626707] LustreError: 412655:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d901a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6003.631384] LustreError: 412655:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6003.653605] Lustre: Unmounted lustre-client [ 6004.532736] LustreError: 412675:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c2c49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6004.536183] LustreError: 412675:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 6004.555125] LustreError: 412675:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6004.558303] LustreError: 412675:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6004.593087] Lustre: Unmounted lustre-client [ 6005.172527] Lustre: DEBUG MARKER: Iteration 1 [ 6005.380248] LustreError: 412838:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6005.380329] LustreError: 412839:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6005.384774] LustreError: 412838:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6005.420600] Lustre: Mounted lustre-client [ 6005.847512] LustreError: 412939:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f3c98000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6005.849064] LustreError: 412940:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6005.850151] LustreError: 412939:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 6005.851740] LustreError: 412940:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 6005.893078] Lustre: Unmounted lustre-client [ 6007.232569] Key type lgssc unregistered [ 6007.400128] LNet: 413183:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6008.422855] LNet: Removed LNI 192.168.204.44@tcp [ 6008.868275] Key type .llcrypt unregistered [ 6008.870250] Key type ._llcrypt unregistered [ 6009.196637] Key type ._llcrypt registered [ 6009.202588] Key type .llcrypt registered [ 6009.496415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6009.503095] alg: No test for adler32 (adler32-zlib) [ 6010.501462] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6010.773732] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6010.775231] LNet: Accept secure, port 988 [ 6012.383224] Key type lgssc registered [ 6012.972258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6016.934667] Lustre: DEBUG MARKER: Iteration 2 [ 6017.036288] LustreError: 414008:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6017.038218] LustreError: 414009:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6017.040039] LustreError: 414008:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6018.097087] Lustre: Mounted lustre-client [ 6018.098743] Lustre: Skipped 1 previous similar message [ 6018.537929] LustreError: 414113:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c66dd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6018.542574] LustreError: 414113:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6018.542715] LustreError: 414112:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6018.546807] LustreError: 414112:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6018.606088] Lustre: Unmounted lustre-client [ 6019.553630] Key type lgssc unregistered [ 6019.672311] LNet: 414355:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6020.709759] LNet: Removed LNI 192.168.204.44@tcp [ 6020.961108] Key type .llcrypt unregistered [ 6020.962107] Key type ._llcrypt unregistered [ 6021.242290] Key type ._llcrypt registered [ 6021.243331] Key type .llcrypt registered [ 6021.437628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6021.442760] alg: No test for adler32 (adler32-zlib) [ 6022.297350] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6022.376767] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6022.378407] LNet: Accept secure, port 988 [ 6023.951172] Key type lgssc registered [ 6024.292676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6027.931030] Lustre: DEBUG MARKER: Iteration 3 [ 6028.036645] LustreError: 415180:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6028.036678] LustreError: 415181:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6028.042040] LustreError: 415180:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6029.100519] Lustre: Mounted lustre-client [ 6029.572459] LustreError: 415281:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c8e6a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6029.574037] LustreError: 415282:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6029.576530] LustreError: 415281:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6029.622908] Lustre: Unmounted lustre-client [ 6030.554357] Key type lgssc unregistered [ 6030.671447] LNet: 415524:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6031.717666] LNet: Removed LNI 192.168.204.44@tcp [ 6031.947772] Key type .llcrypt unregistered [ 6031.948707] Key type ._llcrypt unregistered [ 6032.242243] Key type ._llcrypt registered [ 6032.243514] Key type .llcrypt registered [ 6032.405848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6032.411399] alg: No test for adler32 (adler32-zlib) [ 6033.274928] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6033.377177] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6033.378820] LNet: Accept secure, port 988 [ 6034.967196] Key type lgssc registered [ 6035.340627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6038.901121] Lustre: DEBUG MARKER: Iteration 4 [ 6039.006402] LustreError: 416349:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6039.007884] LustreError: 416350:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6039.011645] LustreError: 416349:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6040.066965] Lustre: Mounted lustre-client [ 6040.068600] Lustre: Skipped 1 previous similar message [ 6040.583532] LustreError: 416453:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c8e68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6040.588104] LustreError: 416453:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6040.590798] LustreError: 416454:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6040.592684] LustreError: 416454:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6040.649176] Lustre: Unmounted lustre-client [ 6041.699684] Key type lgssc unregistered [ 6041.835615] LNet: 416696:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6042.853938] LNet: Removed LNI 192.168.204.44@tcp [ 6043.117959] Key type .llcrypt unregistered [ 6043.119551] Key type ._llcrypt unregistered [ 6043.423744] Key type ._llcrypt registered [ 6043.424859] Key type .llcrypt registered [ 6043.687993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6043.693329] alg: No test for adler32 (adler32-zlib) [ 6044.553566] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6044.634834] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6044.636280] LNet: Accept secure, port 988 [ 6046.223109] Key type lgssc registered [ 6046.571168] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6050.339955] Lustre: DEBUG MARKER: Iteration 5 [ 6050.442016] LustreError: 417521:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6050.442080] LustreError: 417522:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6050.446979] LustreError: 417521:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6051.497209] Lustre: Mounted lustre-client [ 6051.498124] Lustre: Skipped 1 previous similar message [ 6051.941185] LustreError: 417626:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c7a27000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6051.943986] LustreError: 417625:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6051.944721] LustreError: 417626:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6051.991928] Lustre: Unmounted lustre-client [ 6052.980220] Key type lgssc unregistered [ 6053.102302] LNet: 417867:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6054.117668] LNet: Removed LNI 192.168.204.44@tcp [ 6054.348043] Key type .llcrypt unregistered [ 6054.349661] Key type ._llcrypt unregistered [ 6054.568958] Key type ._llcrypt registered [ 6054.571557] Key type .llcrypt registered [ 6054.786450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6054.791605] alg: No test for adler32 (adler32-zlib) [ 6055.655309] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6055.739177] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6055.741733] LNet: Accept secure, port 988 [ 6057.327134] Key type lgssc registered [ 6057.700377] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6061.422498] Lustre: DEBUG MARKER: Iteration 6 [ 6061.533138] LustreError: 418692:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6061.533183] LustreError: 418693:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6061.539239] LustreError: 418692:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6062.592528] Lustre: Mounted lustre-client [ 6062.594495] Lustre: Skipped 1 previous similar message [ 6063.039641] LustreError: 418794:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c33a4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6063.041436] LustreError: 418795:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6063.044857] LustreError: 418794:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6063.094442] Lustre: Unmounted lustre-client [ 6063.999624] Key type lgssc unregistered [ 6064.135467] LNet: 419037:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6065.189811] LNet: Removed LNI 192.168.204.44@tcp [ 6065.471899] Key type .llcrypt unregistered [ 6065.472908] Key type ._llcrypt unregistered [ 6065.697616] Key type ._llcrypt registered [ 6065.698643] Key type .llcrypt registered [ 6065.900132] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6065.906505] alg: No test for adler32 (adler32-zlib) [ 6066.760468] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6066.840423] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6066.841860] LNet: Accept secure, port 988 [ 6068.423113] Key type lgssc registered [ 6068.789530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6072.445366] Lustre: DEBUG MARKER: Iteration 7 [ 6072.546054] LustreError: 419861:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6072.546084] LustreError: 419862:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6072.549446] LustreError: 419861:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6073.596677] Lustre: Mounted lustre-client [ 6073.597773] Lustre: Skipped 1 previous similar message [ 6074.017834] LustreError: 419962:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7eb169000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6074.020431] LustreError: 419962:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6074.022539] LustreError: 419963:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6074.024854] LustreError: 419963:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6074.072094] Lustre: Unmounted lustre-client [ 6075.052726] Key type lgssc unregistered [ 6075.173283] LNet: 420205:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6076.197730] LNet: Removed LNI 192.168.204.44@tcp [ 6076.416964] Key type .llcrypt unregistered [ 6076.418584] Key type ._llcrypt unregistered [ 6076.679712] Key type ._llcrypt registered [ 6076.680839] Key type .llcrypt registered [ 6076.843830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6076.848632] alg: No test for adler32 (adler32-zlib) [ 6077.697589] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6077.771864] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6077.773243] LNet: Accept secure, port 988 [ 6079.351144] Key type lgssc registered [ 6079.703029] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6082.986530] Lustre: DEBUG MARKER: Iteration 8 [ 6083.092108] LustreError: 421030:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6083.092170] LustreError: 421031:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6083.096148] LustreError: 421030:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6084.146703] Lustre: Mounted lustre-client [ 6084.587495] LustreError: 421134:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c3368800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6084.591399] LustreError: 421134:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6084.592091] LustreError: 421135:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6084.655495] Lustre: Unmounted lustre-client [ 6084.657274] Lustre: Skipped 1 previous similar message [ 6085.584811] Key type lgssc unregistered [ 6085.709449] LNet: 421377:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6086.757800] LNet: Removed LNI 192.168.204.44@tcp [ 6086.993068] Key type .llcrypt unregistered [ 6086.994812] Key type ._llcrypt unregistered [ 6087.272857] Key type ._llcrypt registered [ 6087.273863] Key type .llcrypt registered [ 6087.457393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6087.462382] alg: No test for adler32 (adler32-zlib) [ 6088.314291] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6088.398140] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6088.399673] LNet: Accept secure, port 988 [ 6089.983143] Key type lgssc registered [ 6090.355093] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6093.609473] Lustre: DEBUG MARKER: Iteration 9 [ 6093.716314] LustreError: 422203:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6093.716545] LustreError: 422202:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6093.719872] LustreError: 422203:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6094.776337] Lustre: Mounted lustre-client [ 6094.777416] Lustre: Skipped 1 previous similar message [ 6095.212339] LustreError: 422305:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c66d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6095.214342] LustreError: 422304:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6095.215182] LustreError: 422305:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6095.252843] Lustre: Unmounted lustre-client [ 6096.147975] Key type lgssc unregistered [ 6096.266325] LNet: 422548:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6097.318577] LNet: Removed LNI 192.168.204.44@tcp [ 6097.585245] Key type .llcrypt unregistered [ 6097.586365] Key type ._llcrypt unregistered [ 6097.937452] Key type ._llcrypt registered [ 6097.938538] Key type .llcrypt registered [ 6098.174877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6098.179718] alg: No test for adler32 (adler32-zlib) [ 6099.057442] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6099.150284] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6099.152431] LNet: Accept secure, port 988 [ 6100.735192] Key type lgssc registered [ 6101.129818] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6105.001442] Lustre: DEBUG MARKER: Iteration 10 [ 6105.123234] LustreError: 423374:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6105.123569] LustreError: 423375:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6105.128088] LustreError: 423374:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6106.187362] Lustre: Mounted lustre-client [ 6106.188612] Lustre: Skipped 1 previous similar message [ 6106.608565] LustreError: 423477:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f5080800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6106.611662] LustreError: 423477:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6106.612083] LustreError: 423478:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6106.650583] Lustre: Unmounted lustre-client [ 6107.546842] Key type lgssc unregistered [ 6107.658492] LNet: 423720:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6108.709711] LNet: Removed LNI 192.168.204.44@tcp [ 6108.919932] Key type .llcrypt unregistered [ 6108.920894] Key type ._llcrypt unregistered [ 6109.177204] Key type ._llcrypt registered [ 6109.179133] Key type .llcrypt registered [ 6109.357039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6109.361183] alg: No test for adler32 (adler32-zlib) [ 6110.204246] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6110.278507] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6110.280094] LNet: Accept secure, port 988 [ 6111.855139] Key type lgssc registered [ 6112.189794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6115.593577] Lustre: DEBUG MARKER: Iteration 11 [ 6115.710371] LustreError: 424544:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6115.710456] LustreError: 424545:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6115.715618] LustreError: 424544:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6116.770485] Lustre: Mounted lustre-client [ 6116.771419] Lustre: Skipped 1 previous similar message [ 6117.207095] LustreError: 424648:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c814e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6117.209883] LustreError: 424649:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6117.212246] LustreError: 424648:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6117.245122] Lustre: Unmounted lustre-client [ 6118.172815] Key type lgssc unregistered [ 6118.295464] LNet: 424890:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6119.333404] LNet: Removed LNI 192.168.204.44@tcp [ 6119.551949] Key type .llcrypt unregistered [ 6119.552896] Key type ._llcrypt unregistered [ 6119.793591] Key type ._llcrypt registered [ 6119.794499] Key type .llcrypt registered [ 6119.967093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6119.976433] alg: No test for adler32 (adler32-zlib) [ 6120.839274] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6120.915220] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6120.917796] LNet: Accept secure, port 988 [ 6122.495133] Key type lgssc registered [ 6122.820291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6125.951987] Lustre: DEBUG MARKER: Iteration 12 [ 6126.056919] LustreError: 425716:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6126.056963] LustreError: 425717:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6126.060335] LustreError: 425716:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6127.108492] Lustre: Mounted lustre-client [ 6127.538695] LustreError: 425817:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d01e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6127.541672] LustreError: 425817:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6127.542261] LustreError: 425818:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6127.605525] Lustre: Unmounted lustre-client [ 6128.578415] Key type lgssc unregistered [ 6128.705515] LNet: 426060:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6129.765745] LNet: Removed LNI 192.168.204.44@tcp [ 6130.027902] Key type .llcrypt unregistered [ 6130.028946] Key type ._llcrypt unregistered [ 6130.291954] Key type ._llcrypt registered [ 6130.298904] Key type .llcrypt registered [ 6130.499573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6130.504108] alg: No test for adler32 (adler32-zlib) [ 6131.361103] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6131.439566] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6131.441754] LNet: Accept secure, port 988 [ 6133.023086] Key type lgssc registered [ 6133.323468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6136.529177] Lustre: DEBUG MARKER: Iteration 13 [ 6136.626562] LustreError: 426884:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6136.626612] LustreError: 426885:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6136.631513] LustreError: 426884:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6137.679767] Lustre: Mounted lustre-client [ 6138.086830] LustreError: 426986:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c3e5f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6138.089815] LustreError: 426985:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6138.091358] LustreError: 426986:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6138.145079] Lustre: Unmounted lustre-client [ 6139.064237] Key type lgssc unregistered [ 6139.177468] LNet: 427227:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6140.197396] LNet: Removed LNI 192.168.204.44@tcp [ 6140.406925] Key type .llcrypt unregistered [ 6140.407944] Key type ._llcrypt unregistered [ 6140.608911] Key type ._llcrypt registered [ 6140.609852] Key type .llcrypt registered [ 6140.812367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6140.824206] alg: No test for adler32 (adler32-zlib) [ 6141.677694] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6141.755735] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6141.757349] LNet: Accept secure, port 988 [ 6143.335108] Key type lgssc registered [ 6143.667149] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6147.081266] Lustre: DEBUG MARKER: Iteration 14 [ 6147.180720] LustreError: 428051:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6147.180769] LustreError: 428052:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6147.184544] LustreError: 428051:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6148.239242] Lustre: Mounted lustre-client [ 6148.240701] Lustre: Skipped 1 previous similar message [ 6148.680681] LustreError: 428152:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f3c2a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6148.683636] LustreError: 428153:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6148.684200] LustreError: 428152:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6148.688277] LustreError: 428153:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6148.730769] Lustre: Unmounted lustre-client [ 6149.707469] Key type lgssc unregistered [ 6149.830345] LNet: 428395:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6150.885704] LNet: Removed LNI 192.168.204.44@tcp [ 6151.115947] Key type .llcrypt unregistered [ 6151.116944] Key type ._llcrypt unregistered [ 6151.482227] Key type ._llcrypt registered [ 6151.485421] Key type .llcrypt registered [ 6151.571891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6151.576788] alg: No test for adler32 (adler32-zlib) [ 6152.425629] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6152.512281] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6152.514150] LNet: Accept secure, port 988 [ 6154.103117] Key type lgssc registered [ 6154.445555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6157.792744] Lustre: DEBUG MARKER: Iteration 15 [ 6157.906305] LustreError: 429222:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6157.906368] LustreError: 429221:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6157.913360] LustreError: 429222:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6158.967200] Lustre: Mounted lustre-client [ 6159.389766] LustreError: 429322:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c7a01800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6159.392797] LustreError: 429323:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6159.393496] LustreError: 429322:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6159.436061] Lustre: Unmounted lustre-client [ 6159.436993] Lustre: Skipped 1 previous similar message [ 6160.371538] Key type lgssc unregistered [ 6160.485385] LNet: 429564:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6161.509689] LNet: Removed LNI 192.168.204.44@tcp [ 6161.767142] Key type .llcrypt unregistered [ 6161.768825] Key type ._llcrypt unregistered [ 6162.178109] Key type ._llcrypt registered [ 6162.187408] Key type .llcrypt registered [ 6162.271795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6162.276383] alg: No test for adler32 (adler32-zlib) [ 6163.135510] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6163.209200] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6163.210979] LNet: Accept secure, port 988 [ 6164.791128] Key type lgssc registered [ 6165.132954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6168.459754] Lustre: DEBUG MARKER: Iteration 16 [ 6168.555748] LustreError: 430388:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6168.555837] LustreError: 430389:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6168.560061] LustreError: 430388:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6169.601727] Lustre: Mounted lustre-client [ 6170.018536] LustreError: 430493:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c513e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6170.021866] LustreError: 430492:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6170.026021] LustreError: 430493:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6170.078582] Lustre: Unmounted lustre-client [ 6171.005735] Key type lgssc unregistered [ 6171.110419] LNet: 430735:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6172.133798] LNet: Removed LNI 192.168.204.44@tcp [ 6172.373135] Key type .llcrypt unregistered [ 6172.374818] Key type ._llcrypt unregistered [ 6172.582906] Key type ._llcrypt registered [ 6172.583816] Key type .llcrypt registered [ 6172.791593] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6172.808890] alg: No test for adler32 (adler32-zlib) [ 6173.667714] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6173.745692] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6173.747363] LNet: Accept secure, port 988 [ 6175.319135] Key type lgssc registered [ 6175.643516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6178.850425] Lustre: DEBUG MARKER: Iteration 17 [ 6178.940621] LustreError: 431558:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6178.941595] LustreError: 431559:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6178.945486] LustreError: 431558:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6180.000651] Lustre: Mounted lustre-client [ 6180.001581] Lustre: Skipped 1 previous similar message [ 6180.422848] LustreError: 431662:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7fbf81800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6180.425502] LustreError: 431661:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6180.426246] LustreError: 431662:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6180.473101] Lustre: Unmounted lustre-client [ 6181.424733] Key type lgssc unregistered [ 6181.550291] LNet: 431902:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6182.565512] LNet: Removed LNI 192.168.204.44@tcp [ 6182.786085] Key type .llcrypt unregistered [ 6182.787673] Key type ._llcrypt unregistered [ 6183.072543] Key type ._llcrypt registered [ 6183.074257] Key type .llcrypt registered [ 6183.249739] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6183.254160] alg: No test for adler32 (adler32-zlib) [ 6184.108034] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6184.181711] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6184.183105] LNet: Accept secure, port 988 [ 6185.759116] Key type lgssc registered [ 6186.101743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6189.154124] Lustre: DEBUG MARKER: Iteration 18 [ 6189.248212] LustreError: 432728:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6189.248278] LustreError: 432729:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6189.252468] LustreError: 432728:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6190.308306] Lustre: Mounted lustre-client [ 6190.738232] LustreError: 432836:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c814d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6190.741975] LustreError: 432836:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6190.743227] LustreError: 432835:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6190.746867] LustreError: 432835:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6190.766196] Lustre: Unmounted lustre-client [ 6191.690063] Key type lgssc unregistered [ 6191.806564] LNet: 433077:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6192.869802] LNet: Removed LNI 192.168.204.44@tcp [ 6193.119953] Key type .llcrypt unregistered [ 6193.120966] Key type ._llcrypt unregistered [ 6193.466092] Key type ._llcrypt registered [ 6193.467399] Key type .llcrypt registered [ 6193.654779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6193.659249] alg: No test for adler32 (adler32-zlib) [ 6194.519637] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6194.605316] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6194.607968] LNet: Accept secure, port 988 [ 6196.191106] Key type lgssc registered [ 6196.589652] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6200.115076] Lustre: DEBUG MARKER: Iteration 19 [ 6200.227514] LustreError: 433900:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6200.227975] LustreError: 433902:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6200.232750] LustreError: 433900:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6201.290445] Lustre: Mounted lustre-client [ 6201.825224] LustreError: 434001:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d07c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6201.828102] LustreError: 434001:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6201.830260] LustreError: 434002:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6201.832095] LustreError: 434002:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6201.871253] Lustre: Unmounted lustre-client [ 6202.919656] Key type lgssc unregistered [ 6203.043494] LNet: 434246:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6204.070221] LNet: Removed LNI 192.168.204.44@tcp [ 6204.326210] Key type .llcrypt unregistered [ 6204.327429] Key type ._llcrypt unregistered [ 6204.610603] Key type ._llcrypt registered [ 6204.612412] Key type .llcrypt registered [ 6204.827777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6204.842705] alg: No test for adler32 (adler32-zlib) [ 6205.687518] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6205.760893] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6205.762334] LNet: Accept secure, port 988 [ 6207.343144] Key type lgssc registered [ 6207.688248] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6211.425725] Lustre: DEBUG MARKER: Iteration 20 [ 6211.517532] LustreError: 435070:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6211.517566] LustreError: 435071:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6211.521046] LustreError: 435070:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6212.567220] Lustre: Mounted lustre-client [ 6212.981286] LustreError: 435168:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f5d69000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6212.987248] LustreError: 435168:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 6213.086138] Lustre: Unmounted lustre-client [ 6214.069179] Key type lgssc unregistered [ 6214.196319] LNet: 435414:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6215.269806] LNet: Removed LNI 192.168.204.44@tcp [ 6215.489933] Key type .llcrypt unregistered [ 6215.490888] Key type ._llcrypt unregistered [ 6215.718736] Key type ._llcrypt registered [ 6215.727502] Key type .llcrypt registered [ 6215.926559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6215.931560] alg: No test for adler32 (adler32-zlib) [ 6216.797358] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6216.876053] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6216.878251] LNet: Accept secure, port 988 [ 6218.455106] Key type lgssc registered [ 6218.764216] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6222.496765] Lustre: DEBUG MARKER: Iteration 21 [ 6222.597442] LustreError: 436240:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6222.597713] LustreError: 436239:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6222.601984] LustreError: 436240:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6223.653047] Lustre: Mounted lustre-client [ 6224.103968] LustreError: 436344:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c4c32000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6224.110318] LustreError: 436344:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6224.113432] LustreError: 436344:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6224.136112] Lustre: Unmounted lustre-client [ 6225.345690] Key type lgssc unregistered [ 6225.473698] LNet: 436586:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6226.534341] LNet: Removed LNI 192.168.204.44@tcp [ 6226.889463] Key type .llcrypt unregistered [ 6226.890855] Key type ._llcrypt unregistered [ 6227.196040] Key type ._llcrypt registered [ 6227.196943] Key type .llcrypt registered [ 6227.364406] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6227.369876] alg: No test for adler32 (adler32-zlib) [ 6228.232189] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6228.325592] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6228.328541] LNet: Accept secure, port 988 [ 6229.911109] Key type lgssc registered [ 6230.260298] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6233.834319] Lustre: DEBUG MARKER: Iteration 22 [ 6233.950486] LustreError: 437411:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6233.950755] LustreError: 437412:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6233.954522] LustreError: 437411:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6235.003455] Lustre: Mounted lustre-client [ 6235.439289] LustreError: 437514:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c336c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6235.445185] LustreError: 437514:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6235.446854] LustreError: 437514:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6235.483596] Lustre: Unmounted lustre-client [ 6236.637530] Key type lgssc unregistered [ 6236.755474] LNet: 437757:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6237.797707] LNet: Removed LNI 192.168.204.44@tcp [ 6238.185647] Key type .llcrypt unregistered [ 6238.187764] Key type ._llcrypt unregistered [ 6238.496231] Key type ._llcrypt registered [ 6238.497192] Key type .llcrypt registered [ 6238.668419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6238.673709] alg: No test for adler32 (adler32-zlib) [ 6239.567992] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6239.675092] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6239.676651] LNet: Accept secure, port 988 [ 6241.263193] Key type lgssc registered [ 6241.694176] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6245.814167] Lustre: DEBUG MARKER: Iteration 23 [ 6245.930411] LustreError: 438581:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6245.930485] LustreError: 438582:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6245.934969] LustreError: 438581:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6246.990588] Lustre: Mounted lustre-client [ 6247.496276] LustreError: 438689:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9396000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6247.498850] LustreError: 438689:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6247.501208] LustreError: 438688:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 6247.503015] LustreError: 438688:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6247.553057] Lustre: Unmounted lustre-client [ 6248.600659] Key type lgssc unregistered [ 6248.724497] LNet: 438931:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6249.765736] LNet: Removed LNI 192.168.204.44@tcp [ 6250.058751] Key type .llcrypt unregistered [ 6250.061225] Key type ._llcrypt unregistered [ 6250.382177] Key type ._llcrypt registered [ 6250.383025] Key type .llcrypt registered [ 6250.537379] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6250.542143] alg: No test for adler32 (adler32-zlib) [ 6251.424666] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6251.520286] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6251.522484] LNet: Accept secure, port 988 [ 6253.111210] Key type lgssc registered [ 6253.576761] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6257.591204] Lustre: DEBUG MARKER: Iteration 24 [ 6257.744778] LustreError: 439755:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6257.744815] LustreError: 439756:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6257.753888] LustreError: 439755:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6258.807726] Lustre: Mounted lustre-client [ 6259.222311] LustreError: 439860:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dc3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6259.226553] LustreError: 439860:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6259.228132] LustreError: 439860:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6259.252366] Lustre: Unmounted lustre-client [ 6260.213937] Key type lgssc unregistered [ 6260.353865] LNet: 440102:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6261.413740] LNet: Removed LNI 192.168.204.44@tcp [ 6261.660887] Key type .llcrypt unregistered [ 6261.661787] Key type ._llcrypt unregistered [ 6261.877225] Key type ._llcrypt registered [ 6261.878058] Key type .llcrypt registered [ 6262.087642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6262.092156] alg: No test for adler32 (adler32-zlib) [ 6262.946778] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6263.018881] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6263.020185] LNet: Accept secure, port 988 [ 6264.591093] Key type lgssc registered [ 6264.886710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6268.517874] Lustre: DEBUG MARKER: Iteration 25 [ 6268.617899] LustreError: 440926:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6268.617951] LustreError: 440927:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6268.623196] LustreError: 440926:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6269.670574] Lustre: Mounted lustre-client [ 6270.114085] LustreError: 441029:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dcc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6270.118227] LustreError: 441029:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6270.120837] LustreError: 441029:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6270.165530] Lustre: Unmounted lustre-client [ 6271.177540] Key type lgssc unregistered [ 6271.293651] LNet: 441273:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6272.358334] LNet: Removed LNI 192.168.204.44@tcp [ 6272.688058] Key type .llcrypt unregistered [ 6272.688990] Key type ._llcrypt unregistered [ 6272.966464] Key type ._llcrypt registered [ 6272.968191] Key type .llcrypt registered [ 6273.153843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6273.158190] alg: No test for adler32 (adler32-zlib) [ 6274.012810] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6274.091352] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6274.093385] LNet: Accept secure, port 988 [ 6275.679166] Key type lgssc registered [ 6276.073787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6280.013325] Lustre: DEBUG MARKER: Iteration 26 [ 6280.134933] LustreError: 442098:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6280.134985] LustreError: 442099:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6280.138977] LustreError: 442098:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6281.197326] Lustre: Mounted lustre-client [ 6281.725254] LustreError: 442199:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f3c2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6281.727506] LustreError: 442200:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6281.728168] LustreError: 442199:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6281.775331] Lustre: Unmounted lustre-client [ 6282.815882] Key type lgssc unregistered [ 6282.934735] LNet: 442441:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6284.006677] LNet: Removed LNI 192.168.204.44@tcp [ 6284.358292] Key type .llcrypt unregistered [ 6284.359459] Key type ._llcrypt unregistered [ 6284.597720] Key type ._llcrypt registered [ 6284.599621] Key type .llcrypt registered [ 6284.895756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6284.901882] alg: No test for adler32 (adler32-zlib) [ 6285.759224] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6285.842410] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6285.844163] LNet: Accept secure, port 988 [ 6287.423169] Key type lgssc registered [ 6287.808582] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6291.765276] Lustre: DEBUG MARKER: Iteration 27 [ 6291.863582] LustreError: 443265:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6291.863673] LustreError: 443266:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6291.867627] LustreError: 443265:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6292.925103] Lustre: Mounted lustre-client [ 6292.925996] Lustre: Skipped 1 previous similar message [ 6293.346926] LustreError: 443370:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6293.348870] LustreError: 443369:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6293.349845] LustreError: 443370:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6293.388054] Lustre: Unmounted lustre-client [ 6294.567750] Key type lgssc unregistered [ 6294.679485] LNet: 443612:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6295.718443] LNet: Removed LNI 192.168.204.44@tcp [ 6296.081673] Key type .llcrypt unregistered [ 6296.083780] Key type ._llcrypt unregistered [ 6296.362114] Key type ._llcrypt registered [ 6296.362995] Key type .llcrypt registered [ 6296.582803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6296.588670] alg: No test for adler32 (adler32-zlib) [ 6297.440641] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6297.519125] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6297.522046] LNet: Accept secure, port 988 [ 6299.103164] Key type lgssc registered [ 6299.544011] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6303.315334] Lustre: DEBUG MARKER: Iteration 28 [ 6303.412238] LustreError: 444436:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6303.413986] LustreError: 444437:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6303.416888] LustreError: 444436:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6304.471926] Lustre: Mounted lustre-client [ 6304.908962] LustreError: 444542:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c4104000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6304.912290] LustreError: 444542:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6304.912730] LustreError: 444541:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6305.017858] Lustre: Unmounted lustre-client [ 6306.220654] Key type lgssc unregistered [ 6306.341481] LNet: 444783:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6307.365687] LNet: Removed LNI 192.168.204.44@tcp [ 6307.608394] Key type .llcrypt unregistered [ 6307.609738] Key type ._llcrypt unregistered [ 6307.915226] Key type ._llcrypt registered [ 6307.918710] Key type .llcrypt registered [ 6308.079421] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6308.084121] alg: No test for adler32 (adler32-zlib) [ 6308.934584] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6309.009133] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6309.010692] LNet: Accept secure, port 988 [ 6310.591115] Key type lgssc registered [ 6310.927845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6314.617896] Lustre: DEBUG MARKER: Iteration 29 [ 6314.744984] LustreError: 445608:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6314.745121] LustreError: 445607:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6314.749353] LustreError: 445608:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6315.800676] Lustre: Mounted lustre-client [ 6316.317581] LustreError: 445708:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9397800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6316.320113] LustreError: 445709:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6316.320284] LustreError: 445708:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6316.365624] Lustre: Unmounted lustre-client [ 6317.398662] Key type lgssc unregistered [ 6317.513564] LNet: 445951:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6318.565710] LNet: Removed LNI 192.168.204.44@tcp [ 6318.793031] Key type .llcrypt unregistered [ 6318.794138] Key type ._llcrypt unregistered [ 6319.008562] Key type ._llcrypt registered [ 6319.009650] Key type .llcrypt registered [ 6319.203252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6319.207958] alg: No test for adler32 (adler32-zlib) [ 6320.058550] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6320.130714] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6320.132150] LNet: Accept secure, port 988 [ 6321.703164] Key type lgssc registered [ 6322.053826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6325.326386] Lustre: DEBUG MARKER: Iteration 30 [ 6325.420697] LustreError: 446776:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6325.420717] LustreError: 446777:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6325.424209] LustreError: 446776:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6326.478289] Lustre: Mounted lustre-client [ 6326.479272] Lustre: Skipped 1 previous similar message [ 6326.881870] LustreError: 446882:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dc3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6326.887880] LustreError: 446882:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6326.932067] Lustre: Unmounted lustre-client [ 6327.890262] Key type lgssc unregistered [ 6328.009504] LNet: 447124:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6329.062193] LNet: Removed LNI 192.168.204.44@tcp [ 6329.356264] Key type .llcrypt unregistered [ 6329.357875] Key type ._llcrypt unregistered [ 6329.632322] Key type ._llcrypt registered [ 6329.633254] Key type .llcrypt registered [ 6329.810712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6329.815267] alg: No test for adler32 (adler32-zlib) [ 6330.664837] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6330.744919] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6330.747218] LNet: Accept secure, port 988 [ 6332.327218] Key type lgssc registered [ 6332.736720] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6336.393624] Lustre: DEBUG MARKER: Iteration 31 [ 6336.515841] LustreError: 447947:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6336.515877] LustreError: 447949:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6336.519838] LustreError: 447947:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6337.572611] Lustre: Mounted lustre-client [ 6338.084982] LustreError: 448050:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9393000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6338.087312] LustreError: 448051:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6338.089663] LustreError: 448050:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6338.123449] Lustre: Unmounted lustre-client [ 6339.250396] Key type lgssc unregistered [ 6339.381659] LNet: 448294:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6340.458331] LNet: Removed LNI 192.168.204.44@tcp [ 6340.700274] Key type .llcrypt unregistered [ 6340.701273] Key type ._llcrypt unregistered [ 6340.932458] Key type ._llcrypt registered [ 6340.940509] Key type .llcrypt registered [ 6341.195032] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6341.210730] alg: No test for adler32 (adler32-zlib) [ 6342.066672] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6342.158605] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6342.160982] LNet: Accept secure, port 988 [ 6343.751145] Key type lgssc registered [ 6344.268485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6347.718104] Lustre: DEBUG MARKER: Iteration 32 [ 6347.819928] LustreError: 449120:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6347.820307] LustreError: 449119:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6347.824742] LustreError: 449120:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6348.886190] Lustre: Mounted lustre-client [ 6348.887643] Lustre: Skipped 1 previous similar message [ 6349.495831] LustreError: 449221:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7f5d11000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6349.502315] LustreError: 449221:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6349.505046] LustreError: 449221:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6349.540415] Lustre: Unmounted lustre-client [ 6350.601171] Key type lgssc unregistered [ 6350.736672] LNet: 449464:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6351.782074] LNet: Removed LNI 192.168.204.44@tcp [ 6352.053303] Key type .llcrypt unregistered [ 6352.054881] Key type ._llcrypt unregistered [ 6352.340586] Key type ._llcrypt registered [ 6352.341581] Key type .llcrypt registered [ 6352.659779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6352.664355] alg: No test for adler32 (adler32-zlib) [ 6353.512209] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6353.590924] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6353.593153] LNet: Accept secure, port 988 [ 6355.175089] Key type lgssc registered [ 6355.511345] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6358.909719] Lustre: DEBUG MARKER: Iteration 33 [ 6359.038868] LustreError: 450288:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6359.038970] LustreError: 450289:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6359.043037] LustreError: 450288:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6360.108396] Lustre: Mounted lustre-client [ 6360.627080] LustreError: 450391:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c33a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6360.630194] LustreError: 450391:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6360.631324] LustreError: 450390:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6360.633561] LustreError: 450390:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6360.684965] Lustre: Unmounted lustre-client [ 6361.680751] Key type lgssc unregistered [ 6361.802803] LNet: 450633:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6362.854226] LNet: Removed LNI 192.168.204.44@tcp [ 6363.085933] Key type .llcrypt unregistered [ 6363.086934] Key type ._llcrypt unregistered [ 6363.327495] Key type ._llcrypt registered [ 6363.328322] Key type .llcrypt registered [ 6363.605047] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6363.612045] alg: No test for adler32 (adler32-zlib) [ 6364.465132] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6364.552375] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6364.554423] LNet: Accept secure, port 988 [ 6366.143123] Key type lgssc registered [ 6366.507577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6370.071253] Lustre: DEBUG MARKER: Iteration 34 [ 6370.188268] LustreError: 451458:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6370.188314] LustreError: 451459:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6370.195244] LustreError: 451458:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6371.262672] Lustre: Mounted lustre-client [ 6371.262672] Lustre: Mounted lustre-client [ 6371.737581] LustreError: 451561:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c662e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6371.740178] LustreError: 451561:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6371.743388] LustreError: 451560:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6371.745486] LustreError: 451560:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6371.781972] Lustre: Unmounted lustre-client [ 6372.702372] Key type lgssc unregistered [ 6372.810468] LNet: 451804:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6373.861846] LNet: Removed LNI 192.168.204.44@tcp [ 6374.091882] Key type .llcrypt unregistered [ 6374.092813] Key type ._llcrypt unregistered [ 6374.300074] Key type ._llcrypt registered [ 6374.300953] Key type .llcrypt registered [ 6374.486273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6374.491775] alg: No test for adler32 (adler32-zlib) [ 6375.349466] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6375.422364] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6375.424952] LNet: Accept secure, port 988 [ 6376.999104] Key type lgssc registered [ 6377.321603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6380.684723] Lustre: DEBUG MARKER: Iteration 35 [ 6380.799634] LustreError: 452628:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6380.799973] LustreError: 452629:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6380.803487] LustreError: 452628:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6381.846322] Lustre: Mounted lustre-client [ 6382.261513] LustreError: 452731:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dcc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6382.266380] LustreError: 452731:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6382.268076] LustreError: 452731:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6382.309048] Lustre: Unmounted lustre-client [ 6383.301693] Key type lgssc unregistered [ 6383.430893] LNet: 452974:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6384.485790] LNet: Removed LNI 192.168.204.44@tcp [ 6384.805458] Key type .llcrypt unregistered [ 6384.807517] Key type ._llcrypt unregistered [ 6385.068359] Key type ._llcrypt registered [ 6385.074285] Key type .llcrypt registered [ 6385.285311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6385.289628] alg: No test for adler32 (adler32-zlib) [ 6386.129183] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6386.203295] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6386.204942] LNet: Accept secure, port 988 [ 6387.783153] Key type lgssc registered [ 6388.112105] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6391.416533] Lustre: DEBUG MARKER: Iteration 36 [ 6391.508913] LustreError: 453798:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6391.509210] LustreError: 453799:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6391.513907] LustreError: 453798:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6392.566511] Lustre: Mounted lustre-client [ 6392.567852] Lustre: Skipped 1 previous similar message [ 6392.992971] LustreError: 453902:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c7a04800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6392.997559] LustreError: 453902:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6393.001153] LustreError: 453901:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6393.002894] LustreError: 453901:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6393.033857] Lustre: Unmounted lustre-client [ 6393.978044] Key type lgssc unregistered [ 6394.090286] LNet: 454144:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6395.109733] LNet: Removed LNI 192.168.204.44@tcp [ 6395.331896] Key type .llcrypt unregistered [ 6395.332830] Key type ._llcrypt unregistered [ 6395.582202] Key type ._llcrypt registered [ 6395.583305] Key type .llcrypt registered [ 6395.789257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6395.794339] alg: No test for adler32 (adler32-zlib) [ 6396.650668] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6396.737765] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6396.739214] LNet: Accept secure, port 988 [ 6398.319110] Key type lgssc registered [ 6398.671537] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6402.466993] Lustre: DEBUG MARKER: Iteration 37 [ 6402.560935] LustreError: 454969:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6402.560971] LustreError: 454970:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6402.567016] LustreError: 454969:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6403.618216] Lustre: Mounted lustre-client [ 6404.060289] LustreError: 455073:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7e71a8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6404.063780] LustreError: 455073:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6404.064635] LustreError: 455074:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6404.067950] LustreError: 455074:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6404.095087] Lustre: Unmounted lustre-client [ 6405.351648] Key type lgssc unregistered [ 6405.466711] LNet: 455316:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6406.501721] LNet: Removed LNI 192.168.204.44@tcp [ 6406.783312] Key type .llcrypt unregistered [ 6406.785058] Key type ._llcrypt unregistered [ 6407.034335] Key type ._llcrypt registered [ 6407.035739] Key type .llcrypt registered [ 6407.233848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6407.244421] alg: No test for adler32 (adler32-zlib) [ 6408.118927] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6408.191970] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6408.193397] LNet: Accept secure, port 988 [ 6409.767131] Key type lgssc registered [ 6410.113104] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6413.204592] Lustre: DEBUG MARKER: Iteration 38 [ 6413.299304] LustreError: 456140:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6413.299678] LustreError: 456141:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6413.304081] LustreError: 456140:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6414.357058] Lustre: Mounted lustre-client [ 6414.357913] Lustre: Skipped 1 previous similar message [ 6414.782273] LustreError: 456245:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7cb377000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6414.784562] LustreError: 456244:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6414.785074] LustreError: 456245:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6414.839739] Lustre: Unmounted lustre-client [ 6415.973540] Key type lgssc unregistered [ 6416.086802] LNet: 456488:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6417.125515] LNet: Removed LNI 192.168.204.44@tcp [ 6417.317044] Key type .llcrypt unregistered [ 6417.317979] Key type ._llcrypt unregistered [ 6417.611190] Key type ._llcrypt registered [ 6417.620171] Key type .llcrypt registered [ 6417.726722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6417.731060] alg: No test for adler32 (adler32-zlib) [ 6418.580974] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6418.661486] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6418.662771] LNet: Accept secure, port 988 [ 6420.239117] Key type lgssc registered [ 6420.566577] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6423.549519] Lustre: DEBUG MARKER: Iteration 39 [ 6423.638894] LustreError: 457312:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6423.639968] LustreError: 457313:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6423.642523] LustreError: 457312:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6424.697795] Lustre: Mounted lustre-client [ 6424.699085] Lustre: Skipped 1 previous similar message [ 6425.115663] LustreError: 457418:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9dc1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6425.118796] LustreError: 457417:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6425.119916] LustreError: 457418:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6425.158042] Lustre: Unmounted lustre-client [ 6426.127360] Key type lgssc unregistered [ 6426.233599] LNet: 457660:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6427.301812] LNet: Removed LNI 192.168.204.44@tcp [ 6427.513021] Key type .llcrypt unregistered [ 6427.514109] Key type ._llcrypt unregistered [ 6427.778364] Key type ._llcrypt registered [ 6427.779325] Key type .llcrypt registered [ 6427.918230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6427.922450] alg: No test for adler32 (adler32-zlib) [ 6428.764405] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6428.835790] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6428.837176] LNet: Accept secure, port 988 [ 6430.415104] Key type lgssc registered [ 6430.722488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6434.294093] Lustre: DEBUG MARKER: Iteration 40 [ 6434.400223] LustreError: 458485:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6434.400260] LustreError: 458486:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6434.407039] LustreError: 458485:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6435.454941] Lustre: Mounted lustre-client [ 6435.457951] Lustre: Skipped 1 previous similar message [ 6435.893745] LustreError: 458590:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9394800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6435.898286] LustreError: 458590:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6435.944182] Lustre: Unmounted lustre-client [ 6436.936680] Key type lgssc unregistered [ 6437.048487] LNet: 458833:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6438.117712] LNet: Removed LNI 192.168.204.44@tcp [ 6438.345974] Key type .llcrypt unregistered [ 6438.347114] Key type ._llcrypt unregistered [ 6438.627929] Key type ._llcrypt registered [ 6438.628728] Key type .llcrypt registered [ 6438.803657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6438.808470] alg: No test for adler32 (adler32-zlib) [ 6439.657618] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6439.732676] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6439.734965] LNet: Accept secure, port 988 [ 6441.311122] Key type lgssc registered [ 6441.647627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6445.109777] Lustre: DEBUG MARKER: Iteration 41 [ 6445.219821] LustreError: 459658:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6445.219894] LustreError: 459659:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6445.224225] LustreError: 459658:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6446.278680] Lustre: Mounted lustre-client [ 6446.720936] LustreError: 459761:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c34a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6446.725727] LustreError: 459761:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6446.728731] LustreError: 459761:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6446.815950] Lustre: Unmounted lustre-client [ 6446.817274] Lustre: Skipped 1 previous similar message [ 6447.736138] Key type lgssc unregistered [ 6447.856470] LNet: 460003:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6448.869737] LNet: Removed LNI 192.168.204.44@tcp [ 6449.150575] Key type .llcrypt unregistered [ 6449.152665] Key type ._llcrypt unregistered [ 6449.441523] Key type ._llcrypt registered [ 6449.442415] Key type .llcrypt registered [ 6449.616166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6449.620662] alg: No test for adler32 (adler32-zlib) [ 6450.465125] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6450.539804] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6450.541169] LNet: Accept secure, port 988 [ 6452.119113] Key type lgssc registered [ 6452.536159] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6455.916688] Lustre: DEBUG MARKER: Iteration 42 [ 6456.105956] LustreError: 460828:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6456.106045] LustreError: 460827:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6456.112200] LustreError: 460828:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6457.173703] Lustre: Mounted lustre-client [ 6457.603204] LustreError: 460933:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7feedd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6457.605397] LustreError: 460932:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6457.607308] LustreError: 460933:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6457.659167] Lustre: Unmounted lustre-client [ 6458.974809] Key type lgssc unregistered [ 6459.114386] LNet: 461172:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6460.134613] LNet: Removed LNI 192.168.204.44@tcp [ 6460.506885] Key type .llcrypt unregistered [ 6460.509283] Key type ._llcrypt unregistered [ 6460.790919] Key type ._llcrypt registered [ 6460.791867] Key type .llcrypt registered [ 6460.959783] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6460.963828] alg: No test for adler32 (adler32-zlib) [ 6461.804464] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6461.875114] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6461.876707] LNet: Accept secure, port 988 [ 6463.455153] Key type lgssc registered [ 6464.043210] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6468.271602] Lustre: DEBUG MARKER: Iteration 43 [ 6468.375089] LustreError: 461996:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6468.375456] LustreError: 461997:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6468.378725] LustreError: 461996:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6469.422426] Lustre: Mounted lustre-client [ 6469.844014] LustreError: 462098:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9397000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6469.846938] LustreError: 462098:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6469.847916] LustreError: 462099:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6469.914505] Lustre: Unmounted lustre-client [ 6471.225787] Key type lgssc unregistered [ 6471.351386] LNet: 462341:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6472.422713] LNet: Removed LNI 192.168.204.44@tcp [ 6472.784349] Key type .llcrypt unregistered [ 6472.786534] Key type ._llcrypt unregistered [ 6473.028810] Key type ._llcrypt registered [ 6473.029820] Key type .llcrypt registered [ 6473.239942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6473.244412] alg: No test for adler32 (adler32-zlib) [ 6474.124927] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6474.218952] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6474.220774] LNet: Accept secure, port 988 [ 6475.807258] Key type lgssc registered [ 6476.378300] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6480.560580] Lustre: DEBUG MARKER: Iteration 44 [ 6480.663479] LustreError: 463166:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6480.663506] LustreError: 463167:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6480.668969] LustreError: 463166:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6481.721618] Lustre: Mounted lustre-client [ 6481.722587] Lustre: Skipped 1 previous similar message [ 6482.163358] LustreError: 463270:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7e7278800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6482.166140] LustreError: 463270:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6482.167572] LustreError: 463269:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6482.169907] LustreError: 463269:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6482.217145] Lustre: Unmounted lustre-client [ 6483.179789] Key type lgssc unregistered [ 6483.294314] LNet: 463511:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6484.325706] LNet: Removed LNI 192.168.204.44@tcp [ 6484.564105] Key type .llcrypt unregistered [ 6484.565022] Key type ._llcrypt unregistered [ 6484.780633] Key type ._llcrypt registered [ 6484.781584] Key type .llcrypt registered [ 6484.983286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6484.987773] alg: No test for adler32 (adler32-zlib) [ 6485.830213] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6485.903621] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6485.905173] LNet: Accept secure, port 988 [ 6487.479107] Key type lgssc registered [ 6487.786759] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6490.930077] Lustre: DEBUG MARKER: Iteration 45 [ 6491.023511] LustreError: 464336:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6491.023574] LustreError: 464337:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6491.030016] LustreError: 464336:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6492.086907] Lustre: Mounted lustre-client [ 6492.510763] LustreError: 464440:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c8c43000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6492.513313] LustreError: 464441:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6492.513824] LustreError: 464440:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6492.551081] Lustre: Unmounted lustre-client [ 6493.454243] Key type lgssc unregistered [ 6493.566310] LNet: 464682:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6494.629681] LNet: Removed LNI 192.168.204.44@tcp [ 6494.844075] Key type .llcrypt unregistered [ 6494.845035] Key type ._llcrypt unregistered [ 6495.124243] Key type ._llcrypt registered [ 6495.125164] Key type .llcrypt registered [ 6495.290978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6495.295445] alg: No test for adler32 (adler32-zlib) [ 6496.140876] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6496.214287] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6496.215813] LNet: Accept secure, port 988 [ 6497.791112] Key type lgssc registered [ 6498.106556] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6501.205362] Lustre: DEBUG MARKER: Iteration 46 [ 6501.299330] LustreError: 465506:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6501.299378] LustreError: 465507:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6501.303677] LustreError: 465506:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6502.347140] Lustre: Mounted lustre-client [ 6502.780976] LustreError: 465604:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c814a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6502.784140] LustreError: 465604:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6502.784521] LustreError: 465605:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6502.789896] LustreError: 465605:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6502.809664] Lustre: Unmounted lustre-client [ 6503.736972] Key type lgssc unregistered [ 6503.863495] LNet: 465846:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6504.933688] LNet: Removed LNI 192.168.204.44@tcp [ 6505.157900] Key type .llcrypt unregistered [ 6505.158823] Key type ._llcrypt unregistered [ 6505.364218] Key type ._llcrypt registered [ 6505.366677] Key type .llcrypt registered [ 6505.584903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6505.590654] alg: No test for adler32 (adler32-zlib) [ 6506.448894] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6506.524894] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6506.526168] LNet: Accept secure, port 988 [ 6508.111129] Key type lgssc registered [ 6508.430568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6511.812509] Lustre: DEBUG MARKER: Iteration 47 [ 6511.927171] LustreError: 466675:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6511.927212] LustreError: 466676:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6511.930915] LustreError: 466675:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6512.987907] Lustre: Mounted lustre-client [ 6512.988923] Lustre: Skipped 1 previous similar message [ 6513.409929] LustreError: 466780:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7fe00b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6513.416683] LustreError: 466780:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6513.419424] LustreError: 466780:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6513.479071] Lustre: Unmounted lustre-client [ 6514.680841] Key type lgssc unregistered [ 6514.838446] LNet: 467022:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6515.878554] LNet: Removed LNI 192.168.204.44@tcp [ 6516.267095] Key type .llcrypt unregistered [ 6516.268844] Key type ._llcrypt unregistered [ 6516.487147] Key type ._llcrypt registered [ 6516.488326] Key type .llcrypt registered [ 6516.696768] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6516.700717] alg: No test for adler32 (adler32-zlib) [ 6517.566180] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6517.653568] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6517.655098] LNet: Accept secure, port 988 [ 6519.247192] Key type lgssc registered [ 6519.693160] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6523.230859] Lustre: DEBUG MARKER: Iteration 48 [ 6523.345076] LustreError: 467847:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6523.345431] LustreError: 467848:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6523.350674] LustreError: 467847:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6524.398770] Lustre: Mounted lustre-client [ 6524.400521] Lustre: Skipped 1 previous similar message [ 6524.838954] LustreError: 467949:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d9a8d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6524.843043] LustreError: 467949:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6524.844873] LustreError: 467949:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6524.893045] Lustre: Unmounted lustre-client [ 6524.894614] Lustre: Skipped 1 previous similar message [ 6525.797097] Key type lgssc unregistered [ 6525.921773] LNet: 468192:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6526.949723] LNet: Removed LNI 192.168.204.44@tcp [ 6527.156843] Key type .llcrypt unregistered [ 6527.157986] Key type ._llcrypt unregistered [ 6527.381105] Key type ._llcrypt registered [ 6527.382516] Key type .llcrypt registered [ 6527.556619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6527.563140] alg: No test for adler32 (adler32-zlib) [ 6528.434190] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6528.517141] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6528.518624] LNet: Accept secure, port 988 [ 6530.127250] Key type lgssc registered [ 6530.523551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6533.999406] Lustre: DEBUG MARKER: Iteration 49 [ 6534.103854] LustreError: 469017:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6534.103893] LustreError: 469016:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6534.108820] LustreError: 469017:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6535.163695] Lustre: Mounted lustre-client [ 6535.164608] Lustre: Skipped 1 previous similar message [ 6535.601896] LustreError: 469118:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c49be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6535.605269] LustreError: 469118:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6535.605759] LustreError: 469119:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 6535.645174] Lustre: Unmounted lustre-client [ 6536.632250] Key type lgssc unregistered [ 6536.753496] LNet: 469359:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6537.765705] LNet: Removed LNI 192.168.204.44@tcp [ 6537.958842] Key type .llcrypt unregistered [ 6537.959700] Key type ._llcrypt unregistered [ 6538.202390] Key type ._llcrypt registered [ 6538.203294] Key type .llcrypt registered [ 6538.361582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6538.365847] alg: No test for adler32 (adler32-zlib) [ 6539.205013] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6539.274719] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6539.276124] LNet: Accept secure, port 988 [ 6540.847121] Key type lgssc registered [ 6541.174128] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6544.797693] Lustre: DEBUG MARKER: Iteration 50 [ 6544.900084] LustreError: 470184:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 6544.900379] LustreError: 470185:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 6544.904472] LustreError: 470184:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6545.954239] Lustre: Mounted lustre-client [ 6545.955624] Lustre: Skipped 1 previous similar message [ 6546.375154] LustreError: 470285:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7d99b8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6546.377516] LustreError: 470286:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6546.378360] LustreError: 470285:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6546.409845] Lustre: Unmounted lustre-client [ 6547.285286] Key type lgssc unregistered [ 6547.393502] LNet: 470527:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6548.453705] LNet: Removed LNI 192.168.204.44@tcp [ 6548.646869] Key type .llcrypt unregistered [ 6548.647666] Key type ._llcrypt unregistered [ 6548.871354] Key type ._llcrypt registered [ 6548.872309] Key type .llcrypt registered [ 6549.053712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6549.059191] alg: No test for adler32 (adler32-zlib) [ 6549.899258] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 6549.971055] LNet: Added LNI 192.168.204.44@tcp [8/256/0/180] [ 6549.972478] LNet: Accept secure, port 988 [ 6551.551095] Key type lgssc registered [ 6551.849580] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6555.508275] Lustre: Mounted lustre-client [ 6557.485115] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 15:30:57 (1752694257) [ 6574.047109] Lustre: 471866:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752694258/real 1752694258] req@ffff9eb7c5a6d500 x1837833125898112/t0(0) o36->lustre-MDT0000-mdc-ffff9eb7c9396000@192.168.204.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1752694274 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6574.053286] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6574.062901] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection restored to (at 192.168.204.144@tcp) [ 6589.407131] Lustre: 471866:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752694274/real 1752694274] req@ffff9eb7c5a6d500 x1837833125898112/t0(0) o36->lustre-MDT0000-mdc-ffff9eb7c9396000@192.168.204.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1752694290 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6589.418688] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6589.430421] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection restored to (at 192.168.204.144@tcp) [ 6605.791189] Lustre: 471866:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752694290/real 1752694290] req@ffff9eb7c5a6d500 x1837833125898112/t0(0) o36->lustre-MDT0000-mdc-ffff9eb7c9396000@192.168.204.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1752694306 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6605.805543] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6605.822739] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection restored to (at 192.168.204.144@tcp) [ 6621.151097] Lustre: 471866:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752694306/real 1752694306] req@ffff9eb7c5a6d500 x1837833125898112/t0(0) o36->lustre-MDT0000-mdc-ffff9eb7c9396000@192.168.204.144@tcp:12/10 lens 496/440 e 0 to 1 dl 1752694322 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6621.157274] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection to lustre-MDT0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6621.165099] Lustre: lustre-MDT0000-mdc-ffff9eb7c9396000: Connection restored to (at 192.168.204.144@tcp) [ 6621.600597] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 15:32:02 (1752694322) [ 6626.946560] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 15:32:07 (1752694327) [ 6629.872361] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 15:32:10 (1752694330) [ 6631.787231] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 15:32:12 (1752694332) [ 6638.202731] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 15:32:18 (1752694338) [ 6650.166961] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 15:32:30 (1752694350) [ 6652.268838] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 15:32:32 (1752694352) [ 6669.279112] Lustre: 470719:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752694354/real 1752694354] req@ffff9eb7f53e7100 x1837833126927232/t0(0) o4->lustre-OST0000-osc-ffff9eb7c9396000@192.168.204.144@tcp:6/4 lens 4584/448 e 0 to 1 dl 1752694370 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6669.279136] Lustre: lustre-OST0000-osc-ffff9eb7c9396000: Connection to lustre-OST0000 (at 192.168.204.144@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6669.284712] Lustre: 470719:0:(client.c:2453:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 6757.233494] Lustre: DEBUG MARKER: oleg444-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9eb7c8149800.ost_server_uuid 50 [ 6757.677651] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9eb7c8149800.ost_server_uuid in IDLE state after 0 sec [ 6758.134534] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6758.626702] Lustre: DEBUG MARKER: == sanityn test complete, duration 6623 sec ============== 15:34:19 (1752694459) [ 6759.109226] Lustre: DEBUG MARKER: === sanityn: start cleanup 15:34:19 (1752694459) === [ 6823.846115] LustreError: 478135:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c8149800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6823.849062] LustreError: 478135:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6823.863067] Lustre: Unmounted lustre-client [ 6824.950897] Lustre: DEBUG MARKER: === sanityn: finish cleanup 15:35:25 (1752694525) === [ 6825.245613] LustreError: 478440:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9eb7c9396000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6825.248445] LustreError: 478440:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 6825.253173] LustreError: 478440:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6825.254743] LustreError: 478440:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6825.284319] Lustre: Unmounted lustre-client [ 6863.339157] Key type lgssc unregistered [ 6863.450427] LNet: 479119:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6864.485676] LNet: Removed LNI 192.168.204.44@tcp [ 6864.717464] Key type .llcrypt unregistered [ 6864.718542] Key type ._llcrypt unregistered