[ 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 523267551 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002010] Switched APIC routing to physical x2apic. [ 0.003012] kvm-guest: setup PV IPIs [ 0.005852] ..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.006024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007016] pid_max: default: 32768 minimum: 301 [ 0.008135] LSM: Security Framework initializing [ 0.009063] Yama: becoming mindful. [ 0.010058] SELinux: Initializing. [ 0.011000] *** VALIDATE selinux *** [ 0.018182] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024262] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026125] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027119] *** VALIDATE tmpfs *** [ 0.029056] *** VALIDATE proc *** [ 0.031072] *** VALIDATE cgroup *** [ 0.032009] *** VALIDATE cgroup2 *** [ 0.033256] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034155] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036031] Spectre V2 : User space: Vulnerable [ 0.037009] Speculative Store Bypass: Vulnerable [ 0.039427] debug: unmapping init [mem 0xffffffffb0659000-0xffffffffb0660fff] [ 0.041161] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.042715] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.043021] ... version: 2 [ 0.044016] ... bit width: 48 [ 0.045010] ... generic registers: 4 [ 0.046011] ... value mask: 0000ffffffffffff [ 0.047011] ... max period: 00007fffffffffff [ 0.048012] ... fixed-purpose events: 3 [ 0.049018] ... event mask: 000000070000000f [ 0.050312] rcu: Hierarchical SRCU implementation. [ 0.055043] smp: Bringing up secondary CPUs ... [ 0.057396] x86: Booting SMP configuration: [ 0.058023] .... node #0, CPUs: #1 #2 #3 [ 0.066453] smp: Brought up 1 node, 4 CPUs [ 0.068011] smpboot: Max logical packages: 1 [ 0.069014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.093000] node 0 deferred pages initialised in 20ms [ 0.095095] devtmpfs: initialized [ 0.096367] x86/mm: Memory block size: 128MB [ 0.101151] gcov: version magic: 0x41383552 [ 0.104249] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.107097] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.110310] pinctrl core: initialized pinctrl subsystem [ 0.113174] [ 0.113798] ************************************************************* [ 0.116011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.119011] ** ** [ 0.121009] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.124011] ** ** [ 0.126060] ** This means that this kernel is built to expose internal ** [ 0.129012] ** IOMMU data structures, which may compromise security on ** [ 0.131011] ** your system. ** [ 0.133013] ** ** [ 0.135008] ** If you see this message and you are not debugging the ** [ 0.137010] ** kernel, report this immediately to your vendor! ** [ 0.140013] ** ** [ 0.143011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.146051] ************************************************************* [ 0.149580] NET: Registered protocol family 16 [ 0.152934] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.155060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.156000] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.159108] cpuidle: using governor menu [ 0.161833] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.165000] PCI: Using configuration type 1 for base access [ 0.167125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.181232] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.182018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.183699] cryptd: max_cpu_qlen set to 1000 [ 0.187334] ACPI: Added _OSI(Module Device) [ 0.188000] ACPI: Added _OSI(Processor Device) [ 0.188000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.189014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.194608] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.203399] ACPI: Interpreter enabled [ 0.204000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.204000] ACPI: Using IOAPIC for interrupt routing [ 0.205177] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.209470] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.220513] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.223048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.226019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.229151] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.236987] acpiphp: Slot [2] registered [ 0.238175] acpiphp: Slot [3] registered [ 0.240154] acpiphp: Slot [4] registered [ 0.242156] acpiphp: Slot [5] registered [ 0.243166] acpiphp: Slot [6] registered [ 0.245161] acpiphp: Slot [7] registered [ 0.247132] acpiphp: Slot [8] registered [ 0.249109] acpiphp: Slot [9] registered [ 0.250099] acpiphp: Slot [10] registered [ 0.253141] acpiphp: Slot [11] registered [ 0.254231] acpiphp: Slot [12] registered [ 0.256263] acpiphp: Slot [13] registered [ 0.258110] acpiphp: Slot [14] registered [ 0.260093] acpiphp: Slot [15] registered [ 0.261075] acpiphp: Slot [16] registered [ 0.263090] acpiphp: Slot [17] registered [ 0.264000] acpiphp: Slot [18] registered [ 0.265116] acpiphp: Slot [19] registered [ 0.266098] acpiphp: Slot [20] registered [ 0.267071] acpiphp: Slot [21] registered [ 0.268062] acpiphp: Slot [22] registered [ 0.270081] acpiphp: Slot [23] registered [ 0.271089] acpiphp: Slot [24] registered [ 0.273112] acpiphp: Slot [25] registered [ 0.274102] acpiphp: Slot [26] registered [ 0.276131] acpiphp: Slot [27] registered [ 0.277100] acpiphp: Slot [28] registered [ 0.279099] acpiphp: Slot [29] registered [ 0.281074] acpiphp: Slot [30] registered [ 0.282095] acpiphp: Slot [31] registered [ 0.283047] PCI host bridge to bus 0000:00 [ 0.284026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.287023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.289026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.294032] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.297031] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.300023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.303216] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.310938] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.316799] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.321000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.324015] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.326067] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.327015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.332022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.334478] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.336929] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.338041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.341907] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.345012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.353600] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.358562] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.369076] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.380015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.390017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.410019] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.423442] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.430013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.435025] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.448021] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.459311] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.461433] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.464742] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.469418] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.471246] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.475058] iommu: Default domain type: Passthrough [ 0.476000] SCSI subsystem initialized [ 0.477356] ACPI: bus type USB registered [ 0.480133] usbcore: registered new interface driver usbfs [ 0.482087] usbcore: registered new interface driver hub [ 0.485118] usbcore: registered new device driver usb [ 0.489179] pps_core: LinuxPPS API ver. 1 registered [ 0.490000] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.500064] PTP clock support registered [ 0.514200] EDAC MC: Ver: 3.0.0 [ 0.516000] PCI: Using ACPI for IRQ routing [ 0.519498] NetLabel: Initializing [ 0.520011] NetLabel: domain hash size = 128 [ 0.522013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.525040] NetLabel: unlabeled traffic allowed by default [ 0.527034] vgaarb: loaded [ 0.528000] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.530012] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.542370] clocksource: Switched to clocksource kvm-clock [ 0.686743] VFS: Disk quotas dquot_6.6.0 [ 0.688419] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.693511] *** VALIDATE ramfs *** [ 0.694716] *** VALIDATE hugetlbfs *** [ 0.696428] pnp: PnP ACPI init [ 0.701979] pnp: PnP ACPI: found 6 devices [ 0.730855] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.734900] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.738417] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.742526] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.745086] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.748086] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.750673] NET: Registered protocol family 2 [ 0.753053] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.757978] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.761988] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.772153] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.779906] TCP: Hash tables configured (established 65536 bind 65536) [ 0.789543] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.800276] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.807615] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.810230] NET: Registered protocol family 1 [ 0.830800] RPC: Registered named UNIX socket transport module. [ 0.832768] RPC: Registered udp transport module. [ 0.846291] RPC: Registered tcp transport module. [ 0.848183] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.858801] NET: Registered protocol family 44 [ 0.860051] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.861653] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.871446] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.873114] PCI: CLS 0 bytes, default 64 [ 0.876734] Unpacking initramfs... [ 2.593282] debug: unmapping init [mem 0xffff9e757cc64000-0xffff9e757ffcffff] [ 2.599054] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.601130] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.603985] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.305819] Initialise system trusted keyrings [ 3.307820] Key type blacklist registered [ 3.310496] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.323771] zbud: loaded [ 3.327700] *** VALIDATE nfs *** [ 3.331391] *** VALIDATE nfs4 *** [ 3.333953] pstore: using deflate compression [ 3.338596] Platform Keyring initialized [ 3.698071] NET: Registered protocol family 38 [ 3.699530] Key type asymmetric registered [ 3.703744] Asymmetric key parser 'x509' registered [ 3.705813] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.709369] io scheduler mq-deadline registered [ 3.711272] io scheduler kyber registered [ 3.713473] io scheduler bfq registered [ 3.715532] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.718703] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.721370] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.723974] ACPI: Power Button [PWRF] [ 3.980944] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.299296] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.792676] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.897110] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.027775] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.062273] Non-volatile memory driver v1.3 [ 5.063824] Linux agpgart interface v0.103 [ 5.278819] virtio_blk virtio1: [vda] 133160 512-byte logical blocks (68.2 MB/65.0 MiB) [ 5.299078] vda: detected capacity change from 0 to 68177920 [ 5.370717] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.384796] vdb: detected capacity change from 0 to 1073741824 [ 5.434972] libphy: Fixed MDIO Bus: probed [ 5.477066] usbcore: registered new interface driver usbserial_generic [ 5.492960] usbserial: USB Serial support registered for generic [ 5.504116] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.524788] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.526540] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.555549] mousedev: PS/2 mouse device common for all mice [ 5.570971] rtc_cmos 00:05: RTC can wake from S4 [ 5.581583] rtc_cmos 00:05: registered as rtc0 [ 5.591701] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.601285] intel_pstate: CPU model not supported [ 5.619676] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.633241] hid: raw HID events driver (C) Jiri Kosina [ 5.647236] usbcore: registered new interface driver usbhid [ 5.652493] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.664068] usbhid: USB HID core driver [ 5.664254] drop_monitor: Initializing network drop monitor service [ 5.664378] Initializing XFRM netlink socket [ 5.664719] NET: Registered protocol family 10 [ 5.678811] Segment Routing with IPv6 [ 5.699370] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.709276] NET: Registered protocol family 17 [ 5.739660] mpls_gso: MPLS GSO support [ 5.756507] RAS: Correctable Errors collector initialized. [ 5.763671] AVX version of gcm_enc/dec engaged. [ 5.765198] AES CTR mode by8 optimization enabled [ 6.137455] sched_clock: Marking stable (6137430429, 0)->(7860835536, -1723405107) [ 6.143741] registered taskstats version 1 [ 6.153484] Loading compiled-in X.509 certificates [ 6.165479] zswap: loaded using pool lzo/zbud [ 6.304925] Key type big_key registered [ 6.351355] Key type encrypted registered [ 6.352792] ima: No TPM chip found, activating TPM-bypass! [ 6.361951] ima: Allocated hash algorithm: sha1 [ 6.363766] ima: No architecture policies found [ 6.370950] evm: Initialising EVM extended attributes: [ 6.382531] evm: security.selinux [ 6.391721] evm: security.ima [ 6.400553] evm: security.capability [ 6.412659] evm: HMAC attrs: 0x1 [ 6.431512] rtc_cmos 00:05: setting system clock to 2025-09-24 18:03:13 UTC (1758736993) [ 6.472325] debug: unmapping init [mem 0xffffffffb1603000-0xffffffffb17fffff] [ 6.493382] debug: unmapping init [mem 0xffffffffb0382000-0xffffffffb0658fff] [ 6.510240] Write protecting the kernel read-only data: 28672k [ 6.522454] debug: unmapping init [mem 0xffffffffaea03000-0xffffffffaebfffff] [ 6.530180] debug: unmapping init [mem 0xffffffffaf314000-0xffffffffaf3fffff] [ 6.783473] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 6.830534] systemd[1]: Detected virtualization kvm. [ 6.848260] systemd[1]: Detected architecture x86-64. [ 6.860728] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.933611] systemd[1]: No hostname configured. [ 6.945891] systemd[1]: Set hostname to . [ 6.947619] random: systemd: uninitialized urandom read (16 bytes read) [ 6.956123] systemd[1]: Initializing machine ID from random generator. [ 7.752219] random: systemd: uninitialized urandom read (16 bytes read) [ 7.767959] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 7.816689] random: systemd: uninitialized urandom read (16 bytes read) [ 7.830925] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 7.857126] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local File Systems. [ 7.951912] urandom_read: 6 callbacks suppressed [ 7.951924] random: systemd: uninitialized urandom read (16 bytes read) [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ 9.875863] hrtimer: interrupt took 7853031 ns [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 11.274326] device-mapper: uevent: version 1.0.3 [ 11.297107] 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 ] Started udev Coldplug all Devices. [ OK ] Mounted Kernel Configuration File System. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 14.699428] virtio_net virtio0 ens2: renamed from eth0 Starting dracut initqueue hook... [ 15.331939] scsi host0: ata_piix [ 15.386179] scsi host1: ata_piix [ 15.393522] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 15.396038] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 19.978893] random: crng init done [ 24.214787] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 27.154989] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 30.311347] printk: systemd: 26 output lines suppressed due to ratelimiting [ 31.148854] SELinux: Disabled at runtime. [ 31.256098] 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) [ 31.286791] systemd[1]: Detected virtualization kvm. [ 31.291678] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 33.140777] systemd[1]: initrd-switch-root.service: Succeeded. [ 33.144891] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 33.153934] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 33.159493] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 33.173603] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 33.227044] systemd[1]: Starting Journal Service... Starting Journal Service... [ 33.247489] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. [ 33.554688] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... Starting Remount Root and Kernel File Systems... [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 34.815653] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 36.140076] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 36.479698] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 36.978268] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 37.173533] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit)[ 41.804401] Key type dns_resolver registered [ *** ] A start job is running for Configur…-only root support (8s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ ***] A start job is running for Configur…-only root support (9s / no limit)[ 42.953671] NFS: Registering the id_resolver key type [ 42.955585] Key type id_resolver registered [ 42.963928] Key type id_legacy registered [ **] A start job is running for Configur…only root support (10s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. Starting Network Manager... Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ 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. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg205-client login: [ 95.546311] libcfs: loading out-of-tree module taints kernel. [ 95.691140] Key type ._llcrypt registered [ 95.692686] Key type .llcrypt registered [ 96.059426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 96.071653] alg: No test for adler32 (adler32-zlib) [ 97.155585] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 97.630156] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 99.327204] Key type lgssc registered [ 100.666441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 190.927441] Lustre: Mounted lustre-client [ 194.952398] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 213.044394] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing check_logdir /tmp/testlogs/ [ 215.999268] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing yml_node [ 216.544719] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 24s idle [ 219.821026] Lustre: DEBUG MARKER: Client: 2.16.58.53 [ 221.753758] Lustre: DEBUG MARKER: MDS: 2.16.58.53 [ 223.814261] Lustre: DEBUG MARKER: OSS: 2.16.58.53 [ 225.170981] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Sep 24 14:06:51 EDT 2025 [ 237.653560] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 238.829209] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 240.273759] Lustre: DEBUG MARKER: === sanityn: start setup 14:07:06 (1758737226) === [ 240.838224] Lustre: Mounted lustre-client [ 243.986760] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing check_config_client /mnt/lustre [ 257.317949] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 261.599275] Lustre: lustre-OST0000-osc-ffff9e75c737d800: disconnect after 21s idle [ 261.602478] Lustre: Skipped 1 previous similar message [ 265.972901] Lustre: DEBUG MARKER: === sanityn: finish setup 14:07:31 (1758737251) === [ 268.137858] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 14:07:34 (1758737254) [ 274.988820] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 14:07:41 (1758737261) [ 280.186942] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 14:07:46 (1758737266) [ 285.600382] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 14:07:51 (1758737271) [ 291.296765] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 14:07:57 (1758737277) [ 296.451448] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 14:08:02 (1758737282) [ 301.735633] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 14:08:07 (1758737287) [ 307.042645] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 14:08:12 (1758737292) [ 308.165774] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 309.447568] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 14:08:15 (1758737295) [ 315.181920] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 14:08:21 (1758737301) [ 324.668706] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 14:08:29 (1758737309) [ 333.287257] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 24s idle [ 333.290272] Lustre: Skipped 1 previous similar message [ 334.309774] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 14:08:39 (1758737319) [ 342.428670] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 14:08:47 (1758737327) [ 348.642267] Lustre: lustre-OST0001-osc-ffff9e75c737d800: disconnect after 22s idle [ 351.351815] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 14:08:56 (1758737336) [ 359.232775] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 14:09:04 (1758737344) [ 367.245018] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 14:09:12 (1758737352) [ 369.120647] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 24s idle [ 375.532669] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 14:09:21 (1758737361) [ 383.177401] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 14:09:28 (1758737368) [ 391.688270] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 14:09:37 (1758737377) [ 399.455959] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 14:09:44 (1758737384) [ 406.534249] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 14:09:52 (1758737392) [ 407.359314] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 558.811204] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 14:12:24 (1758737544) [ 566.992225] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 14:12:32 (1758737552) [ 572.560400] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 14:12:38 (1758737558) [ 578.154428] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 14:12:44 (1758737564) [ 584.158750] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 14:12:50 (1758737570) [ 590.199800] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 14:12:56 (1758737576) [ 591.676618] Lustre: DEBUG MARKER: chmod [ 598.267606] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 14:13:03 (1758737583) [ 631.200709] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 646.043631] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 14:13:51 (1758737631) [ 696.837825] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 14:14:42 (1758737682) [ 723.195284] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 14:15:09 (1758737709) [ 725.246689] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 726.733765] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 14:15:12 (1758737712) [ 768.273524] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 14:15:54 (1758737754) [ 772.523251] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 14:15:58 (1758737758) [ 773.184765] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.245111] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.280530] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.322478] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.373661] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.417722] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.459847] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.545423] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.607254] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.664381] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.724462] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.795744] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.845530] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.912867] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 773.982200] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.043539] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.089687] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.138993] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.192668] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.249795] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.308258] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.370489] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.448981] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.522981] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.599672] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.663653] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.717237] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.780337] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.828852] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.883383] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.930813] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 774.973481] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.023683] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.065955] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.122446] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.177790] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.243206] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.293764] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.338122] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.406578] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.447424] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.505522] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.564785] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.618136] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.668374] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.713207] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.771241] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.835941] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.873144] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.913811] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 775.956947] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.010159] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.062232] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.110647] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.157719] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.217684] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.291869] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.378445] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.449397] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.537248] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.589327] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.631950] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.678102] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.741678] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.801090] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.840455] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.891220] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 776.952222] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.041582] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.100298] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.140134] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.179524] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.224797] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.283499] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.335495] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.406138] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.487697] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.574158] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.645232] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.700589] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.743826] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.799604] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.880225] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 777.955137] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.028318] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.077065] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.140725] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.193839] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.260312] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.301528] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.370665] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.452623] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.521050] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.595391] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.709937] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.764468] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.817944] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.894925] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 778.963767] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.080193] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.125992] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.170325] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.220402] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.289658] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.365423] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.423910] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.502517] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.587001] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.639263] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.699444] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.793412] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.890616] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 779.955043] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.036820] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.114466] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.219704] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.305925] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.350610] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.413584] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.480102] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.555281] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.620649] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.687741] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.746542] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.830496] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.895845] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 780.952561] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.002808] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.056226] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.119300] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.177730] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.229061] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.272849] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.329952] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.392105] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.461650] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.514905] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.582160] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.637402] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.692904] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.773453] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.822579] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.871240] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.934523] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 781.997418] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.045152] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.100421] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.144423] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.205254] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.266244] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.337126] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.421154] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.483581] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.569456] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.637665] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.692878] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.747103] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.807735] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.899802] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 782.974898] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.059793] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.133376] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.174113] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.236800] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.295112] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.352449] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.419806] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.485159] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.543744] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.595725] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.652791] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.704762] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.779749] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.846288] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.912761] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 783.970439] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.018689] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.085304] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.140988] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.210846] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.270085] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.325038] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.376111] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.437850] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.502615] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.573884] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.665508] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.732625] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.790687] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.849489] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.907228] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.949866] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 784.994152] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.070881] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.150955] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.220578] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.290289] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.358709] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.420877] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.477139] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.536560] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.584811] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.638199] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.683063] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.744176] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.790317] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.850120] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.907357] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 785.948433] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.002159] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.061148] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.106349] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.156922] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.202084] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.244241] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.297426] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.344127] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.397399] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.434931] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.502879] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.561379] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.627736] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.686622] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.735734] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.796876] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.846257] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 786.943895] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.039385] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.118356] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.178145] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.238574] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.303383] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.373227] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.432441] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.480881] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.527366] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.583985] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.652592] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.716580] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.791947] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.842811] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.882946] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.934764] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 787.996284] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.035896] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.081677] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.150077] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.193914] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.265228] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.313977] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.380395] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.428322] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.499879] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.586964] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.654129] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.710833] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.780962] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.852162] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.897819] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.955681] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 788.965767] Lustre: lustre-OST0000-osc-ffff9e75c737d800: disconnect after 21s idle [ 789.030505] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.075847] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.121501] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.200152] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.265232] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.309069] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.377917] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.433833] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.494390] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.556404] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.651621] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.702114] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.758283] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.819902] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.877175] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.923575] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 789.970616] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.009664] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.048056] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.130290] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.218511] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.304238] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.400504] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.486886] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.578333] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.642445] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.724590] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.798162] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.872703] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 790.937264] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.001257] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.065702] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.117496] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.190654] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.261174] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.326191] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.381241] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.438924] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.494983] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.548288] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.606100] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.683342] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.769069] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.833556] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 791.923618] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.024349] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.092239] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.150219] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.221354] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.285701] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.332484] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.388270] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.435400] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.483083] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.547266] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 792.627349] rw_seq_cst_vs_d (29486): drop_caches: 3 [ 798.818838] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 14:16:24 (1758737784) [ 799.165858] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.219604] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.324708] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.518434] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.558307] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.739826] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.766070] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 799.917838] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.057943] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.192174] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.313971] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.451827] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.498420] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.608080] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.723951] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.942402] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 800.970733] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.015121] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.070373] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.144465] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.219419] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.293203] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.325090] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.360251] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.394522] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.481279] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.579548] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.660232] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.697454] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.797702] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 801.893542] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.097896] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.225451] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.312508] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.351487] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.398568] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.547410] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.696466] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.748925] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.792537] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.841448] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 802.930366] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.066425] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.133099] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.295982] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.417114] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.510113] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.548121] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.582799] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.618938] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.680932] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.760164] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.844321] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.930641] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 803.962255] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.003467] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.157550] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.212887] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.283519] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.321864] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.404558] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.546480] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.666120] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.768860] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 804.816559] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.003106] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.066292] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.179397] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.267841] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.308277] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.350206] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.476986] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.517100] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.558647] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.601864] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.644900] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.798905] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.829348] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.898940] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 805.944949] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.088208] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.158919] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.286975] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.416078] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.483711] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.540506] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.604127] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.742063] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.784620] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.935740] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 806.985215] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.086237] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.127906] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.161765] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.369213] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.660841] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.801578] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 807.915674] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.107548] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.132942] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.259210] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.329632] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.365208] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.421052] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.446074] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.526846] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.616476] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.711328] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.824318] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.854892] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 808.934314] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.039021] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.181613] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.309289] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.364563] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.422265] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.518995] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.759213] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.853485] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.901190] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 809.942350] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.017799] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.063922] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.142371] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.251741] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.425506] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.478602] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.608957] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.651988] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.684481] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.777985] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.837853] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.875517] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 810.987520] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.029061] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.093877] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.221206] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.270807] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.326623] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.455348] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.659916] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.743638] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.781858] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.827073] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 811.963528] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.029743] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.079722] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.127564] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.279733] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.417438] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.450535] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.575659] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.749971] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.792525] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.863510] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.913867] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 812.961673] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.091198] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.147967] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.213879] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.305383] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.465063] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.498636] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.570587] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.667227] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.818371] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.867149] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 813.893360] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.018079] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.192890] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.230534] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.348850] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.423603] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.473426] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.559207] Lustre: lustre-OST0001-osc-ffff9e75c737d800: disconnect after 22s idle [ 814.562481] Lustre: Skipped 1 previous similar message [ 814.588426] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.671872] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.707877] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.872508] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.949373] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 814.974173] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.163678] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.233846] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.296911] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.370250] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.411977] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.537744] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.604258] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.776494] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.814449] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 815.951480] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.030213] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.197095] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.382850] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.512278] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.599977] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.731050] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.954317] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 816.995965] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.108238] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.133204] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.152715] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.234049] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.279290] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.414628] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.485273] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.555458] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.692734] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.854277] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 817.954719] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.116861] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.156187] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.214808] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.402768] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.498370] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.524453] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.594101] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.680340] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.754914] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 818.904972] rw_seq_cst_vs_d (30061): drop_caches: 3 [ 825.104426] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 14:16:50 (1758737810) [ 830.919202] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 14:16:56 (1758737816) [ 836.861860] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 14:17:02 (1758737822) [ 867.909932] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 14:17:33 (1758737853) [ 868.415060] bash (32507): drop_caches: 3 [ 871.648746] bash (32507): drop_caches: 3 [ 875.112923] bash (32507): drop_caches: 3 [ 878.333164] bash (32507): drop_caches: 3 [ 881.480391] bash (32507): drop_caches: 3 [ 884.694376] bash (32507): drop_caches: 3 [ 887.867202] bash (32507): drop_caches: 3 [ 891.127827] bash (32507): drop_caches: 3 [ 894.286971] bash (32507): drop_caches: 3 [ 897.439756] bash (32507): drop_caches: 3 [ 900.599519] bash (32507): drop_caches: 3 [ 903.681277] bash (32507): drop_caches: 3 [ 906.809233] bash (32507): drop_caches: 3 [ 910.013572] bash (32507): drop_caches: 3 [ 914.960401] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 14:18:20 (1758737900) [ 923.772181] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 14:18:29 (1758737909) [ 959.252957] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 14:19:05 (1758737945) [ 961.466134] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 962.905125] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 14:19:08 (1758737948) [ 969.004322] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 14:19:14 (1758737954) [ 973.279113] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 22s idle [ 973.282965] Lustre: Skipped 1 previous similar message [ 974.193779] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 14:19:20 (1758737960) [ 1042.223323] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 14:20:28 (1758738028) [ 1049.005487] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 14:20:34 (1758738034) [ 1055.499740] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 14:20:40 (1758738040) [ 1062.760523] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 14:20:48 (1758738048) [ 1064.500206] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1066.406547] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 14:20:51 (1758738051) [ 1073.631231] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 24s idle [ 1073.633645] Lustre: Skipped 4 previous similar messages [ 1074.108485] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 14:20:59 (1758738059) [ 1083.299020] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 14:21:08 (1758738068) [ 1090.731590] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1092.203185] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 14:21:18 (1758738078) [ 1101.750571] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 14:21:26 (1758738086) [ 1102.045271] Lustre: *** cfs_fail_loc=314, val=0*** [ 1103.071418] Lustre: *** cfs_fail_loc=314, val=0*** [ 1103.080173] Lustre: Skipped 2 previous similar messages [ 1110.315308] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 14:21:35 (1758738095) [ 1123.819550] Lustre: *** cfs_fail_loc=314, val=0*** [ 1123.920085] LustreError: lustre-OST0000-osc-ffff9e75c737d800: operation ldlm_enqueue to node 192.168.202.105@tcp failed: rc = -107 [ 1123.926675] Lustre: lustre-OST0000-osc-ffff9e75c737d800: Connection to lustre-OST0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1123.945566] LustreError: lustre-OST0000-osc-ffff9e75c737d800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1123.959488] LustreError: 41754:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9e75c737d800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9e75d868d800) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1123.977480] Lustre: lustre-OST0000-osc-ffff9e75c737d800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 1131.402633] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 14:21:56 (1758738116) [ 1131.702829] LustreError: 42336:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1134.735445] LustreError: 42336:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1141.130908] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 14:22:06 (1758738126) [ 1148.733799] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 14:22:14 (1758738134) [ 1155.781570] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1157.603917] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 14:22:23 (1758738143) [ 1159.216905] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1160.702672] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 14:22:26 (1758738146) [ 1162.274722] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1164.086770] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 14:22:29 (1758738149) [ 1165.361522] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1166.704435] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 14:22:32 (1758738152) [ 1167.862986] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1169.089606] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 14:22:35 (1758738155) [ 1226.131154] Lustre: lustre-OST0000-osc-ffff9e75c737d800: Connection to lustre-OST0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1226.158945] LustreError: lustre-OST0000-osc-ffff9e75c737d800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1226.175285] Lustre: lustre-OST0000-osc-ffff9e75c737d800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 1226.210933] LustreError: lustre-OST0000-osc-ffff9e75c73b2800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1241.582286] Lustre: lustre-OST0001-osc-ffff9e75c73b2800: Connection to lustre-OST0001 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1241.589744] Lustre: Skipped 1 previous similar message [ 1241.600809] LustreError: lustre-OST0001-osc-ffff9e75c73b2800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1241.613410] Lustre: lustre-OST0001-osc-ffff9e75c73b2800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 1241.620892] Lustre: Skipped 1 previous similar message [ 1247.711359] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 21s idle [ 1247.718306] Lustre: Skipped 1 previous similar message [ 1257.900958] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1259.231732] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid in IDLE state after 0 sec [ 1261.514629] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1262.431976] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid in FULL state after 0 sec [ 1265.724825] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1266.873174] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid in IDLE state after 0 sec [ 1269.296968] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1270.395633] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid in FULL state after 0 sec [ 1277.891540] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1279.653067] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e75c737d800.ost_server_uuid in IDLE state after 0 sec [ 1282.877868] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid 50 [ 1283.916832] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9e75c737d800.ost_server_uuid in FULL state after 0 sec [ 1285.474172] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 14:24:31 (1758738271) [ 1287.639606] Lustre: DEBUG MARKER: Race attempt 0 [ 1290.288726] Lustre: DEBUG MARKER: Wait for 49039 49075 for 60 sec... [ 1355.179180] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 14:25:41 (1758738341) [ 1361.777280] Lustre: DEBUG MARKER: start test - cycle (0) [ 1385.181062] Lustre: DEBUG MARKER: start test - cycle (1) [ 1411.736794] Lustre: DEBUG MARKER: start test - cycle (2) [ 1436.842115] Lustre: DEBUG MARKER: start test - cycle (3) [ 1458.705854] Lustre: DEBUG MARKER: start test - cycle (4) [ 1483.986909] Lustre: DEBUG MARKER: start test - cycle (5) [ 1508.162960] Lustre: DEBUG MARKER: start test - cycle (6) [ 1533.045199] Lustre: DEBUG MARKER: start test - cycle (7) [ 1534.431252] Lustre: lustre-OST0000-osc-ffff9e75c737d800: disconnect after 20s idle [ 1534.438600] Lustre: Skipped 7 previous similar messages [ 1555.107656] Lustre: DEBUG MARKER: start test - cycle (8) [ 1581.398261] Lustre: DEBUG MARKER: start test - cycle (9) [ 1605.742339] Lustre: DEBUG MARKER: start test - cycle (10) [ 1635.773231] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 14:30:21 (1758738621) [ 1713.116475] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 14:31:39 (1758738699) [ 1718.514280] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 14:31:44 (1758738704) [ 1724.666588] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 14:31:50 (1758738710) [ 1731.255950] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 14:31:57 (1758738717) [ 1731.489602] Lustre: *** cfs_fail_loc=411, val=0*** [ 1736.813630] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1738.350653] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 14:32:04 (1758738724) [ 1754.874138] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 14:32:20 (1758738740) [ 1769.840814] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 14:32:35 (1758738755) [ 1784.503936] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 14:32:50 (1758738770) [ 1798.576977] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 14:33:04 (1758738784) [ 1810.735560] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 14:33:16 (1758738796) [ 1822.520629] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 14:33:28 (1758738808) [ 1834.299348] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 14:33:40 (1758738820) [ 1844.662590] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 14:33:50 (1758738830) [ 1856.771424] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 14:34:02 (1758738842) [ 1868.427786] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 14:34:14 (1758738854) [ 1880.325646] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 14:34:26 (1758738866) [ 1892.044824] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 14:34:37 (1758738877) [ 2512.352956] Lustre: lustre-OST0000-osc-ffff9e75c737d800: disconnect after 24s idle [ 2512.360236] Lustre: Skipped 12 previous similar messages [ 2982.873663] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 14:52:48 (1758739968) [ 2994.771955] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 14:53:00 (1758739980) [ 3006.705580] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 14:53:12 (1758739992) [ 3018.803749] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 14:53:24 (1758740004) [ 3030.359975] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 14:53:36 (1758740016) [ 3041.380344] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 14:53:47 (1758740027) [ 3052.113288] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 14:53:58 (1758740038) [ 3064.173597] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 14:54:09 (1758740049) [ 3075.823243] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 14:54:21 (1758740061) [ 3143.408260] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 14:55:28 (1758740128) [ 3156.490433] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 14:55:42 (1758740142) [ 3167.733322] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 14:55:53 (1758740153) [ 3179.303969] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 14:56:05 (1758740165) [ 3192.530799] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 14:56:18 (1758740178) [ 3206.260118] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 14:56:32 (1758740192) [ 3208.672200] Lustre: lustre-OST0001-osc-ffff9e75c73b2800: disconnect after 21s idle [ 3208.675096] Lustre: Skipped 4 previous similar messages [ 3219.301941] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 14:56:44 (1758740204) [ 3232.954930] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 14:56:58 (1758740218) [ 3234.256768] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3235.931389] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 14:57:01 (1758740221) [ 3356.621795] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 14:59:02 (1758740342) [ 4200.111305] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 15:13:06 (1758741186) [ 4207.664863] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 15:13:14 (1758741194) [ 4212.192363] Lustre: lustre-OST0000-osc-ffff9e75c737d800: disconnect after 24s idle [ 4212.195615] Lustre: Skipped 8 previous similar messages [ 4214.986789] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 15:13:21 (1758741201) [ 4222.009512] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 15:13:28 (1758741208) [ 4228.860518] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 15:13:35 (1758741215) [ 4236.274317] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 15:13:42 (1758741222) [ 4243.578292] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 15:13:50 (1758741230) [ 4250.249301] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 15:13:56 (1758741236) [ 4256.878934] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 15:14:03 (1758741243) [ 4257.571358] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4258.301072] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 15:14:04 (1758741244) [ 4317.599226] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 15:15:04 (1758741304) [ 4324.136430] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 15:15:10 (1758741310) [ 4330.672484] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 15:15:17 (1758741317) [ 4337.246308] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 15:15:23 (1758741323) [ 4343.816228] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 15:15:30 (1758741330) [ 4350.243272] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 15:15:36 (1758741336) [ 4356.299858] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 15:15:42 (1758741342) [ 4361.755129] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 15:15:48 (1758741348) [ 4362.322987] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4362.934648] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 15:15:49 (1758741349) [ 4890.627717] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 15:24:37 (1758741877) [ 4896.176815] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 15:24:42 (1758741882) [ 4901.906789] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 15:24:48 (1758741888) [ 4907.508911] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 15:24:54 (1758741894) [ 4913.159122] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 15:24:59 (1758741899) [ 4918.765202] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 15:25:05 (1758741905) [ 4924.421226] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 15:25:10 (1758741910) [ 4930.175596] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 15:25:16 (1758741916) [ 4936.387712] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 15:25:22 (1758741922) [ 4936.937509] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4937.538891] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 15:25:24 (1758741924) [ 4938.052153] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4938.684487] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 15:25:25 (1758741925) [ 4939.300697] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4939.959936] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 15:25:26 (1758741926) [ 4940.564737] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4941.205349] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 15:25:27 (1758741927) [ 4941.774969] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4942.356880] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 15:25:28 (1758741928) [ 4942.911463] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4943.550622] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 15:25:30 (1758741930) [ 4944.126442] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4944.789489] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 15:25:31 (1758741931) [ 4945.390411] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4946.042624] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 15:25:32 (1758741932) [ 4946.132168] LustreError: 21370:0:(ldlm_lockd.c:2122:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4948.215222] LustreError: 21370:0:(ldlm_lockd.c:2122:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4953.767234] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 15:25:40 (1758741940) [ 4958.451563] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 15:25:44 (1758741944) [ 4958.558919] LustreError: 260691:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4962.615224] LustreError: 260691:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4962.623810] LustreError: 260691:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4966.679170] LustreError: 260691:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4966.695709] LustreError: 260697:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4970.751183] LustreError: 260697:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4973.078378] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 15:25:59 (1758741959) [ 4980.170325] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 15:26:06 (1758741966) [ 4983.756704] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 15:26:10 (1758741970) [ 4988.175779] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 15:26:14 (1758741974) [ 4995.551243] Lustre: lustre-OST0000-osc-ffff9e75c73b2800: disconnect after 22s idle [ 4995.554318] Lustre: Skipped 5 previous similar messages [ 5012.747900] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 15:26:39 (1758741999) [ 5020.469233] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 15:26:47 (1758742007) [ 5028.210385] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 15:26:54 (1758742014) [ 5041.230551] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 15:27:07 (1758742027) [ 5050.836464] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 15:27:17 (1758742037) [ 5051.420270] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5052.047326] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 15:27:18 (1758742038) [ 5065.102515] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 15:27:31 (1758742051) [ 5079.379629] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 15:27:45 (1758742065) [ 5115.977860] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 15:28:22 (1758742102) [ 5155.844345] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 15:29:02 (1758742142) [ 5158.318771] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5161.196990] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 15:29:07 (1758742147) [ 5164.222911] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 15:29:10 (1758742150) [ 5164.914906] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5165.556211] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 15:29:12 (1758742152) [ 5166.126110] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5166.724943] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 15:29:13 (1758742153) [ 5167.228024] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 5167.795898] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 15:29:14 (1758742154) [ 5168.324448] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5168.893962] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 15:29:15 (1758742155) [ 5171.346849] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 15:29:17 (1758742157) [ 5173.755262] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 15:29:20 (1758742160) [ 5176.855367] LustreError: lustre-MDT0000-mdc-ffff9e75c73b2800: operation ldlm_enqueue to node 192.168.202.105@tcp failed: rc = -35 [ 5176.859222] LustreError: Skipped 1 previous similar message [ 5179.940840] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 15:29:26 (1758742166) [ 5180.083532] LustreError: 2361:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5182.167107] LustreError: 2361:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5187.183288] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 15:29:33 (1758742173) [ 5206.229984] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 15:29:52 (1758742192) [ 5209.158455] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 15:29:55 (1758742195) [ 5213.223932] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 15:29:59 (1758742199) [ 5218.059771] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 15:30:04 (1758742204) [ 5223.266383] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 15:30:09 (1758742209) [ 5230.976269] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 15:30:17 (1758742217) [ 5238.188540] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 15:30:24 (1758742224) [ 5241.858605] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 15:30:28 (1758742228) [ 5245.734042] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 15:30:32 (1758742232) [ 5253.219741] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 15:30:39 (1758742239) [ 5307.047218] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 15:31:33 (1758742293) [ 5444.284974] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 15:33:50 (1758742430) [ 5577.742084] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 15:36:04 (1758742564) [ 5604.831262] Lustre: lustre-OST0001-osc-ffff9e75c73b2800: disconnect after 20s idle [ 5604.833833] Lustre: Skipped 11 previous similar messages [ 5936.806228] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 15:42:03 (1758742923) [ 5974.429310] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 15:42:40 (1758742960) [ 6015.558269] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 15:43:22 (1758743002) [ 6050.975978] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 15:43:57 (1758743037) [ 6127.749906] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 15:45:14 (1758743114) [ 6193.246902] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 15:46:19 (1758743179) [ 6219.231158] Lustre: lustre-OST0001-osc-ffff9e75c73b2800: disconnect after 21s idle [ 6219.234448] Lustre: Skipped 16 previous similar messages [ 6312.206933] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 15:48:18 (1758743298) [ 6315.605716] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 15:48:22 (1758743302) [ 6366.631621] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 15:49:13 (1758743353) [ 6433.687287] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 15:50:20 (1758743420) [ 6437.425044] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 15:50:24 (1758743424) [ 6476.473434] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 15:51:03 (1758743463) [ 6488.314251] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 15:51:14 (1758743474) [ 6529.188092] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 15:51:55 (1758743515) [ 6532.321381] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 15:51:58 (1758743518) [ 6545.133571] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 15:52:11 (1758743531) [ 6545.627952] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 6546.202748] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 15:52:12 (1758743532) [ 6546.728417] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 6547.336641] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 15:52:13 (1758743533) [ 6547.878470] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 6548.459803] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 15:52:15 (1758743535) [ 6548.998771] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 6549.583376] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 15:52:16 (1758743536) [ 6550.080512] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6550.614485] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 15:52:17 (1758743537) [ 6577.702350] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 15:52:44 (1758743564) [ 6579.981519] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 15:52:46 (1758743566) [ 6580.533949] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 6581.143806] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 15:52:47 (1758743567) [ 6588.764698] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 15:52:55 (1758743575) [ 6591.855351] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 15:52:58 (1758743578) [ 6592.374893] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 6592.956597] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 15:52:59 (1758743579) [ 6593.511408] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 6594.167647] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 15:53:00 (1758743580) [ 6594.701985] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 6595.310979] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 15:53:01 (1758743581) [ 6604.356464] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 15:53:10 (1758743590) [ 6604.433359] Lustre: DEBUG MARKER: write [ 6604.450898] LustreError: 14432:0:(ldlm_request.c:1403:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6606.459459] Lustre: DEBUG MARKER: kill 328432 [ 6606.462365] LustreError: 328432:0:(ldlm_request.c:1288:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6609.551096] LustreError: 14432:0:(ldlm_request.c:1403:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6612.495119] LustreError: 328432:0:(ldlm_request.c:1288:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6614.745367] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 15:53:21 (1758743601) [ 6616.929886] LustreError: 329038:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6627.023147] LustreError: 329038:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6629.589268] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 15:53:36 (1758743616) [ 6629.714032] LustreError: 329617:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6631.799129] LustreError: 329617:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 6638.059152] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 15:53:44 (1758743624) [ 6638.620450] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6639.236265] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 15:53:45 (1758743625) [ 6639.329338] Lustre: 14431:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) ### try to drop unset bits 0x59/0x2 ns: lustre-MDT0000-mdc-ffff9e75c737d800 lock: ffff9e75f1c6bcc0/0xb97c354a4b70c7ee lrc: 2/0,0 mode: PW/PW res: [0x200000401:0x1a32:0x0].0x0 bits 0x59/0x0 rrc: 2 type: IBT gid 0 flags: 0x10420402000000 nid: local remote: 0xd8cc45703130446d expref: -99 pid: 330486 timeout: 0 lvb_type: 3 [ 6641.615647] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 15:53:48 (1758743628) [ 6641.705317] Lustre: 5544:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) ### try to drop unset bits 0x59/0x2 ns: lustre-MDT0000-mdc-ffff9e75c737d800 lock: ffff9e75eabe6400/0xb97c354a4b70c83b lrc: 2/0,0 mode: PW/PW res: [0x200000401:0x1a34:0x0].0x0 bits 0x59/0x0 rrc: 2 type: IBT gid 0 flags: 0x10420402000000 nid: local remote: 0xd8cc45703130458c expref: -99 pid: 331072 timeout: 0 lvb_type: 3 [ 6644.185026] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 15:53:50 (1758743630) [ 6644.267339] Lustre: 9506:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) ### try to drop unset bits 0x59/0x2 ns: lustre-MDT0000-mdc-ffff9e75c737d800 lock: ffff9e75f3018000/0xb97c354a4b70c896 lrc: 2/0,0 mode: PW/PW res: [0x200000401:0x1a36:0x0].0x0 bits 0x59/0x0 rrc: 2 type: IBT gid 0 flags: 0x10420402000000 nid: local remote: 0xd8cc4570313046a4 expref: -99 pid: 331650 timeout: 0 lvb_type: 3 [ 6644.280188] Lustre: 9506:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) Skipped 2 previous similar messages [ 6646.716653] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 15:53:53 (1758743633) [ 6646.820340] Lustre: 14432:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) ### try to drop unset bits 0x48/0x13 ns: lustre-MDT0000-mdc-ffff9e75c73b2800 lock: ffff9e75fe261680/0xb97c354a4b70c8ea lrc: 3/0,0 mode: PW/PW res: [0x200000401:0x1a38:0x0].0x0 bits 0x48/0x0 rrc: 2 type: IBT gid 0 flags: 0x810420402000000 nid: local remote: 0xd8cc457031304792 expref: -99 pid: 332234 timeout: 0 lvb_type: 3 [ 6646.831241] Lustre: 14432:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) Skipped 2 previous similar messages [ 6649.135354] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 15:53:55 (1758743635) [ 6651.393367] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 15:53:57 (1758743637) [ 6651.490524] Lustre: 9506:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) ### try to drop unset bits 0x5a/0x5 ns: lustre-MDT0000-mdc-ffff9e75c73b2800 lock: ffff9e75f301a880/0xb97c354a4b70c9ae lrc: 2/0,0 mode: PR/PR res: [0x200000401:0x1a3b:0x0].0x0 bits 0x5a/0x0 rrc: 2 type: IBT gid 0 flags: 0x810420402000000 nid: local remote: 0xd8cc4570313049f3 expref: -99 pid: 333398 timeout: 0 lvb_type: 3 [ 6651.499765] Lustre: 9506:0:(ldlm_inodebits.c:488:ldlm_inodebits_downgrade()) Skipped 3 previous similar messages [ 6653.736592] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 15:54:00 (1758743640) [ 6656.935526] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 6657.484849] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 15:54:04 (1758743644) [ 6658.121612] Lustre: *** cfs_fail_loc=415, val=0*** [ 6664.894178] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 15:54:11 (1758743651) [ 6665.462131] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 6666.018804] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 15:54:12 (1758743652) [ 6666.077266] LustreError: 14432:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6666.082182] LustreError: 14432:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6671.079122] LustreError: 14432:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6681.271076] LustreError: 14432:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6681.273874] LustreError: 14432:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 6683.920896] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 15:54:30 (1758743670) [ 6684.487523] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6685.122147] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 15:54:31 (1758743671) [ 6687.817222] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 15:54:34 (1758743674) [ 6690.162255] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 15:54:36 (1758743676) [ 6695.572789] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 15:54:41 (1758743681) [ 6705.617727] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 15:54:51 (1758743691) [ 6705.898173] LustreError: 329918:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6705.902637] LustreError: 329918:0:(osc_request.c:2959:osc_build_rpc()) Skipped 6 previous similar messages [ 6709.967236] LustreError: 329918:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6709.970760] LustreError: 329918:0:(osc_request.c:2959:osc_build_rpc()) Skipped 1 previous similar message [ 6714.146916] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 15:55:00 (1758743700) [ 6715.744562] LustreError: 339554:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c73b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6715.758065] LustreError: 339554:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6715.786578] Lustre: Unmounted lustre-client [ 6717.045084] LustreError: 339575:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6717.053109] LustreError: 339575:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 6717.061856] LustreError: 339575:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6717.064585] LustreError: 339575:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 6717.091076] Lustre: Unmounted lustre-client [ 6717.999299] Lustre: DEBUG MARKER: Iteration 1 [ 6718.342475] LustreError: 339735:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6718.343701] LustreError: 339736:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6718.352722] LustreError: 339735:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6718.484493] Lustre: Mounted lustre-client [ 6719.316588] LustreError: 339839:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c4eee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6719.325238] LustreError: 339839:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 6719.334344] LustreError: 339839:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6719.338424] LustreError: 339839:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6719.366663] Lustre: Unmounted lustre-client [ 6720.944209] Key type lgssc unregistered [ 6721.125870] LNet: 340084:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6721.129855] LNetError: 340084:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6721.139805] LNet: Removed LNI 192.168.202.5@tcp [ 6721.660264] Key type .llcrypt unregistered [ 6721.662360] Key type ._llcrypt unregistered [ 6721.999319] Key type ._llcrypt registered [ 6722.005060] Key type .llcrypt registered [ 6722.528081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6722.546118] alg: No test for adler32 (adler32-zlib) [ 6723.620397] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6724.025417] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6725.663139] Key type lgssc registered [ 6726.218676] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6730.879031] Lustre: DEBUG MARKER: Iteration 2 [ 6730.997853] LustreError: 340904:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6730.997907] LustreError: 340905:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6731.004115] LustreError: 340904:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6732.077663] Lustre: Mounted lustre-client [ 6732.078971] Lustre: Skipped 1 previous similar message [ 6732.636978] LustreError: 341008:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75d84ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6732.641462] LustreError: 341008:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6732.645061] LustreError: 341007:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6732.647521] LustreError: 341007:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6732.667207] Lustre: Unmounted lustre-client [ 6733.878712] Key type lgssc unregistered [ 6734.006353] LNet: 341248:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6734.009983] LNetError: 341248:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6734.016775] LNet: Removed LNI 192.168.202.5@tcp [ 6734.321120] Key type .llcrypt unregistered [ 6734.322655] Key type ._llcrypt unregistered [ 6734.594772] Key type ._llcrypt registered [ 6734.596629] Key type .llcrypt registered [ 6734.877277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6734.886712] alg: No test for adler32 (adler32-zlib) [ 6735.779663] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6735.888672] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6737.487164] Key type lgssc registered [ 6737.961021] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6741.825190] Lustre: DEBUG MARKER: Iteration 3 [ 6741.950140] LustreError: 342066:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6741.951483] LustreError: 342067:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6741.956171] LustreError: 342066:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6743.009442] Lustre: Mounted lustre-client [ 6743.010794] Lustre: Skipped 1 previous similar message [ 6743.590771] LustreError: 342173:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75fe3a2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6743.595309] LustreError: 342174:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6743.596048] LustreError: 342173:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6743.615110] Lustre: Unmounted lustre-client [ 6744.739386] Key type lgssc unregistered [ 6744.879821] LNet: 342416:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6744.883606] LNetError: 342416:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6744.893534] LNet: Removed LNI 192.168.202.5@tcp [ 6745.178180] Key type .llcrypt unregistered [ 6745.179055] Key type ._llcrypt unregistered [ 6745.524049] Key type ._llcrypt registered [ 6745.525553] Key type .llcrypt registered [ 6745.742195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6745.749996] alg: No test for adler32 (adler32-zlib) [ 6746.640905] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6746.746642] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6748.343521] Key type lgssc registered [ 6748.862864] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6753.996481] Lustre: DEBUG MARKER: Iteration 4 [ 6754.342613] LustreError: 343234:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6754.343959] LustreError: 343235:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6754.349929] LustreError: 343234:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6755.565567] Lustre: Mounted lustre-client [ 6756.913574] LustreError: 343343:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75fa7fa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6756.932213] LustreError: 343343:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 6756.995951] Lustre: Unmounted lustre-client [ 6759.968354] Key type lgssc unregistered [ 6760.241809] LNet: 343582:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6760.255178] LNetError: 343582:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6760.275873] LNet: Removed LNI 192.168.202.5@tcp [ 6761.050305] Key type .llcrypt unregistered [ 6761.053113] Key type ._llcrypt unregistered [ 6761.664292] Key type ._llcrypt registered [ 6761.665619] Key type .llcrypt registered [ 6762.065748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6762.077989] alg: No test for adler32 (adler32-zlib) [ 6763.231730] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6763.455741] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6765.119562] Key type lgssc registered [ 6766.273708] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6775.152849] Lustre: DEBUG MARKER: Iteration 5 [ 6775.521302] LustreError: 344401:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6775.524454] LustreError: 344403:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6775.530869] LustreError: 344401:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 6776.812925] Lustre: Mounted lustre-client [ 6777.972694] LustreError: 344512:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75da139000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6777.988895] LustreError: 344512:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6777.995387] LustreError: 344513:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6778.028748] Lustre: Unmounted lustre-client [ 6780.240512] Key type lgssc unregistered [ 6780.509459] LNet: 344753:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6780.517929] LNetError: 344753:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6781.551584] LNet: Removed LNI 192.168.202.5@tcp [ 6782.592173] Key type .llcrypt unregistered [ 6782.593849] Key type ._llcrypt unregistered [ 6783.688130] Key type ._llcrypt registered [ 6783.689664] Key type .llcrypt registered [ 6784.512851] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6784.539647] alg: No test for adler32 (adler32-zlib) [ 6785.714763] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6785.981189] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6787.719169] Key type lgssc registered [ 6789.039485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6803.798400] Lustre: DEBUG MARKER: Iteration 6 [ 6804.146331] LustreError: 345569:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6804.174451] LustreError: 345585:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6804.179405] LustreError: 345569:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4975 [ 6805.583258] Lustre: Mounted lustre-client [ 6807.309616] LustreError: 345679:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6807.365781] LustreError: 345679:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6807.370249] LustreError: 345679:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6807.402433] Lustre: Unmounted lustre-client [ 6810.680293] Key type lgssc unregistered [ 6810.954708] LNet: 345922:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6810.973573] LNetError: 345922:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6812.008260] LNet: Removed LNI 192.168.202.5@tcp [ 6813.004419] Key type .llcrypt unregistered [ 6813.009248] Key type ._llcrypt unregistered [ 6813.839859] Key type ._llcrypt registered [ 6813.840936] Key type .llcrypt registered [ 6814.680560] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6814.722166] alg: No test for adler32 (adler32-zlib) [ 6815.803592] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6815.997056] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6817.639280] Key type lgssc registered [ 6818.891587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6827.911833] Lustre: DEBUG MARKER: Iteration 7 [ 6828.178809] LustreError: 346742:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6828.181650] LustreError: 346743:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6828.190339] LustreError: 346742:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 6829.478646] Lustre: Mounted lustre-client [ 6830.484170] LustreError: 346853:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cf84b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6830.492902] LustreError: 346853:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6830.501130] LustreError: 346852:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6830.540631] Lustre: Unmounted lustre-client [ 6832.468389] Key type lgssc unregistered [ 6832.712690] LNet: 347094:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6832.716918] LNetError: 347094:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6832.727712] LNet: Removed LNI 192.168.202.5@tcp [ 6833.241319] Key type .llcrypt unregistered [ 6833.244081] Key type ._llcrypt unregistered [ 6833.682913] Key type ._llcrypt registered [ 6833.687071] Key type .llcrypt registered [ 6834.021127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6834.031399] alg: No test for adler32 (adler32-zlib) [ 6835.025962] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6835.203111] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6836.864751] Key type lgssc registered [ 6837.581212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6844.569475] Lustre: DEBUG MARKER: Iteration 8 [ 6844.807980] LustreError: 347913:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6844.811917] LustreError: 347914:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6844.817389] LustreError: 347913:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6845.974844] Lustre: Mounted lustre-client [ 6845.977788] Lustre: Skipped 1 previous similar message [ 6846.991180] LustreError: 348021:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c5a4a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6847.007586] LustreError: 348020:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6847.030094] Lustre: Unmounted lustre-client [ 6848.745334] Key type lgssc unregistered [ 6848.956938] LNet: 348262:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6848.962676] LNetError: 348262:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6848.975025] LNet: Removed LNI 192.168.202.5@tcp [ 6849.403284] Key type .llcrypt unregistered [ 6849.405051] Key type ._llcrypt unregistered [ 6849.966245] Key type ._llcrypt registered [ 6849.993104] Key type .llcrypt registered [ 6850.148382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6850.157236] alg: No test for adler32 (adler32-zlib) [ 6851.086176] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6851.232522] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6852.855214] Key type lgssc registered [ 6853.394277] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6859.542878] Lustre: DEBUG MARKER: Iteration 9 [ 6859.700648] LustreError: 349079:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6859.701768] LustreError: 349081:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6859.708070] LustreError: 349079:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6860.832466] Lustre: Mounted lustre-client [ 6860.834132] Lustre: Skipped 1 previous similar message [ 6861.576744] LustreError: 349193:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f89c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6861.583738] LustreError: 349194:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6861.583893] LustreError: 349193:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6861.604154] Lustre: Unmounted lustre-client [ 6863.107561] Key type lgssc unregistered [ 6863.271520] LNet: 349435:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6863.275047] LNetError: 349435:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6863.287704] LNet: Removed LNI 192.168.202.5@tcp [ 6863.705247] Key type .llcrypt unregistered [ 6863.708222] Key type ._llcrypt unregistered [ 6864.229374] Key type ._llcrypt registered [ 6864.230902] Key type .llcrypt registered [ 6864.434562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6864.446582] alg: No test for adler32 (adler32-zlib) [ 6865.403173] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6865.552968] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6867.193459] Key type lgssc registered [ 6867.949421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6874.292624] Lustre: DEBUG MARKER: Iteration 10 [ 6874.546407] LustreError: 350254:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6874.550747] LustreError: 350253:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6874.560754] LustreError: 350254:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 6875.745790] Lustre: Mounted lustre-client [ 6876.510420] LustreError: 350365:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f3304000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6876.525705] LustreError: 350365:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6876.530765] LustreError: 350365:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6876.541749] Lustre: Unmounted lustre-client [ 6878.204583] Key type lgssc unregistered [ 6878.372651] LNet: 350607:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6878.379434] LNetError: 350607:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6878.389945] LNet: Removed LNI 192.168.202.5@tcp [ 6878.780243] Key type .llcrypt unregistered [ 6878.782096] Key type ._llcrypt unregistered [ 6879.149345] Key type ._llcrypt registered [ 6879.150750] Key type .llcrypt registered [ 6879.431215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6879.444688] alg: No test for adler32 (adler32-zlib) [ 6880.403426] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6880.548601] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6882.184521] Key type lgssc registered [ 6882.799319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6889.512980] Lustre: DEBUG MARKER: Iteration 11 [ 6889.882106] LustreError: 351425:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6889.884712] LustreError: 351426:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6889.889714] LustreError: 351425:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 6891.081822] Lustre: Mounted lustre-client [ 6892.102708] LustreError: 351535:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9ecd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6892.121649] LustreError: 351535:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6892.125231] LustreError: 351535:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6892.147507] Lustre: Unmounted lustre-client [ 6894.397672] Key type lgssc unregistered [ 6894.654824] LNet: 351776:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6894.659902] LNetError: 351776:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6894.671835] LNet: Removed LNI 192.168.202.5@tcp [ 6895.132177] Key type .llcrypt unregistered [ 6895.133989] Key type ._llcrypt unregistered [ 6895.645521] Key type ._llcrypt registered [ 6895.647440] Key type .llcrypt registered [ 6895.881935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6895.891963] alg: No test for adler32 (adler32-zlib) [ 6896.821331] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6896.960580] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6898.599164] Key type lgssc registered [ 6899.458494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6905.904227] Lustre: DEBUG MARKER: Iteration 12 [ 6906.132937] LustreError: 352595:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6906.134081] LustreError: 352596:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6906.141271] LustreError: 352595:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6907.314457] Lustre: Mounted lustre-client [ 6907.317454] Lustre: Skipped 1 previous similar message [ 6908.158747] LustreError: 352706:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ccc0c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6908.163823] LustreError: 352706:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6908.165871] LustreError: 352705:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6908.184764] Lustre: Unmounted lustre-client [ 6909.820113] Key type lgssc unregistered [ 6910.003340] LNet: 352948:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6910.008349] LNetError: 352948:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6910.022621] LNet: Removed LNI 192.168.202.5@tcp [ 6910.499079] Key type .llcrypt unregistered [ 6910.500539] Key type ._llcrypt unregistered [ 6910.982698] Key type ._llcrypt registered [ 6911.013041] Key type .llcrypt registered [ 6911.202396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6911.209258] alg: No test for adler32 (adler32-zlib) [ 6912.158677] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6912.294672] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6913.919365] Key type lgssc registered [ 6914.680719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6921.380561] Lustre: DEBUG MARKER: Iteration 13 [ 6921.610848] LustreError: 353765:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6921.612463] LustreError: 353766:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6921.618818] LustreError: 353765:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6922.742552] Lustre: Mounted lustre-client [ 6923.485035] LustreError: 353866:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c3cae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6923.497516] LustreError: 353866:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6923.516587] Lustre: Unmounted lustre-client [ 6925.139647] Key type lgssc unregistered [ 6925.319913] LNet: 354114:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6925.324915] LNetError: 354114:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6926.377919] LNet: Removed LNI 192.168.202.5@tcp [ 6926.790838] Key type .llcrypt unregistered [ 6926.792693] Key type ._llcrypt unregistered [ 6927.241589] Key type ._llcrypt registered [ 6927.247072] Key type .llcrypt registered [ 6927.506440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6927.515108] alg: No test for adler32 (adler32-zlib) [ 6928.439894] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6928.596400] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6930.224305] Key type lgssc registered [ 6931.064218] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6936.421511] Lustre: DEBUG MARKER: Iteration 14 [ 6936.584695] LustreError: 354930:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6936.589295] LustreError: 354934:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6936.593923] LustreError: 354930:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6937.723633] Lustre: Mounted lustre-client [ 6937.725197] Lustre: Skipped 1 previous similar message [ 6938.543344] LustreError: 355041:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c625d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6938.549334] LustreError: 355041:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 6938.554131] LustreError: 355040:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6938.585637] Lustre: Unmounted lustre-client [ 6940.129828] Key type lgssc unregistered [ 6940.332793] LNet: 355282:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6940.337919] LNetError: 355282:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6940.348797] LNet: Removed LNI 192.168.202.5@tcp [ 6940.848352] Key type .llcrypt unregistered [ 6940.849983] Key type ._llcrypt unregistered [ 6941.438969] Key type ._llcrypt registered [ 6941.441473] Key type .llcrypt registered [ 6941.834332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6941.843561] alg: No test for adler32 (adler32-zlib) [ 6942.852686] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6942.993810] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6944.656073] Key type lgssc registered [ 6945.564319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6952.697617] Lustre: DEBUG MARKER: Iteration 15 [ 6952.875687] LustreError: 356102:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6952.876238] LustreError: 356103:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6952.886764] LustreError: 356102:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 6954.040421] Lustre: Mounted lustre-client [ 6955.004660] LustreError: 356211:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75d9aac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6955.025906] LustreError: 356214:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6955.030524] LustreError: 356214:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6955.044648] Lustre: Unmounted lustre-client [ 6956.916543] Key type lgssc unregistered [ 6957.126856] LNet: 356453:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6957.131507] LNetError: 356453:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6958.181640] LNet: Removed LNI 192.168.202.5@tcp [ 6958.558253] Key type .llcrypt unregistered [ 6958.561132] Key type ._llcrypt unregistered [ 6958.919473] Key type ._llcrypt registered [ 6958.921172] Key type .llcrypt registered [ 6959.236249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6959.244880] alg: No test for adler32 (adler32-zlib) [ 6960.203685] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6960.341982] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6961.959787] Key type lgssc registered [ 6962.625751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6969.281967] Lustre: DEBUG MARKER: Iteration 16 [ 6969.450432] LustreError: 357270:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6969.452942] LustreError: 357272:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6969.466195] LustreError: 357270:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6970.627995] Lustre: Mounted lustre-client [ 6970.632881] Lustre: Skipped 1 previous similar message [ 6971.631538] LustreError: 357380:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75fa9f5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6971.648508] LustreError: 357380:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6971.669775] Lustre: Unmounted lustre-client [ 6973.890887] Key type lgssc unregistered [ 6974.126726] LNet: 357622:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6974.136485] LNetError: 357622:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6974.158316] LNet: Removed LNI 192.168.202.5@tcp [ 6974.795514] Key type .llcrypt unregistered [ 6974.797099] Key type ._llcrypt unregistered [ 6975.362678] Key type ._llcrypt registered [ 6975.364478] Key type .llcrypt registered [ 6975.711778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6975.722934] alg: No test for adler32 (adler32-zlib) [ 6976.815433] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6977.004066] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6978.745719] Key type lgssc registered [ 6979.793153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6987.452699] Lustre: DEBUG MARKER: Iteration 17 [ 6987.638935] LustreError: 358438:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 6987.640719] LustreError: 358440:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 6987.648293] LustreError: 358438:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 6988.826095] Lustre: Mounted lustre-client [ 6989.932843] LustreError: 358547:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6989.967225] LustreError: 358548:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6989.971203] LustreError: 358548:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6989.997132] Lustre: Unmounted lustre-client [ 6992.146637] Key type lgssc unregistered [ 6992.351584] LNet: 358789:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6992.358531] LNetError: 358789:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6992.368665] LNet: Removed LNI 192.168.202.5@tcp [ 6992.857263] Key type .llcrypt unregistered [ 6992.859215] Key type ._llcrypt unregistered [ 6993.419393] Key type ._llcrypt registered [ 6993.424907] Key type .llcrypt registered [ 6993.737782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6993.749566] alg: No test for adler32 (adler32-zlib) [ 6994.690693] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 6994.849409] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 6996.503210] Key type lgssc registered [ 6997.189224] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7003.861808] Lustre: DEBUG MARKER: Iteration 18 [ 7004.072687] LustreError: 359610:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7004.074654] LustreError: 359609:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7004.083820] LustreError: 359610:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7005.221745] Lustre: Mounted lustre-client [ 7006.140515] LustreError: 359718:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cc1f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7006.165058] LustreError: 359719:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7006.168582] LustreError: 359719:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7006.192111] Lustre: Unmounted lustre-client [ 7008.141821] Key type lgssc unregistered [ 7008.367633] LNet: 359959:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7008.373482] LNetError: 359959:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7008.391524] LNet: Removed LNI 192.168.202.5@tcp [ 7008.884170] Key type .llcrypt unregistered [ 7008.885892] Key type ._llcrypt unregistered [ 7009.386597] Key type ._llcrypt registered [ 7009.403141] Key type .llcrypt registered [ 7009.825078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7009.834714] alg: No test for adler32 (adler32-zlib) [ 7010.814583] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7010.982940] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7012.631182] Key type lgssc registered [ 7013.572843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7020.594857] Lustre: DEBUG MARKER: Iteration 19 [ 7020.809920] LustreError: 360778:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7020.814803] LustreError: 360779:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7020.823150] LustreError: 360778:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 7021.984556] Lustre: Mounted lustre-client [ 7022.803843] LustreError: 360889:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75d9aae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7022.814083] LustreError: 360889:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7022.826884] LustreError: 360889:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7022.830293] LustreError: 360889:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7022.851143] Lustre: Unmounted lustre-client [ 7024.591341] Key type lgssc unregistered [ 7024.789875] LNet: 361132:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7024.798807] LNetError: 361132:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7024.811622] LNet: Removed LNI 192.168.202.5@tcp [ 7025.320206] Key type .llcrypt unregistered [ 7025.326842] Key type ._llcrypt unregistered [ 7025.726205] Key type ._llcrypt registered [ 7025.733795] Key type .llcrypt registered [ 7026.016516] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7026.039730] alg: No test for adler32 (adler32-zlib) [ 7027.025928] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7027.198319] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7028.855201] Key type lgssc registered [ 7029.718463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7036.372125] Lustre: DEBUG MARKER: Iteration 20 [ 7036.566626] LustreError: 361947:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7036.569356] LustreError: 361949:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7036.576952] LustreError: 361947:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 7037.735282] Lustre: Mounted lustre-client [ 7038.645597] LustreError: 362051:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cc212800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7038.665106] LustreError: 362051:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7038.695187] Lustre: Unmounted lustre-client [ 7040.782759] Key type lgssc unregistered [ 7040.974250] LNet: 362299:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7040.984824] LNetError: 362299:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7041.002056] LNet: Removed LNI 192.168.202.5@tcp [ 7041.472221] Key type .llcrypt unregistered [ 7041.474068] Key type ._llcrypt unregistered [ 7042.147396] Key type ._llcrypt registered [ 7042.148646] Key type .llcrypt registered [ 7042.309521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7042.320624] alg: No test for adler32 (adler32-zlib) [ 7043.329921] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7043.516395] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7045.183183] Key type lgssc registered [ 7046.108126] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7053.049274] Lustre: DEBUG MARKER: Iteration 21 [ 7053.223035] LustreError: 363118:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7053.223902] LustreError: 363117:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7053.230936] LustreError: 363118:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7054.419567] Lustre: Mounted lustre-client [ 7054.421792] Lustre: Skipped 1 previous similar message [ 7055.275976] LustreError: 363222:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f30fd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7055.292171] LustreError: 363222:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7055.320921] Lustre: Unmounted lustre-client [ 7057.195751] Key type lgssc unregistered [ 7057.375491] LNet: 363470:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7057.380328] LNetError: 363470:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7057.396260] LNet: Removed LNI 192.168.202.5@tcp [ 7057.912963] Key type .llcrypt unregistered [ 7057.914543] Key type ._llcrypt unregistered [ 7058.320531] Key type ._llcrypt registered [ 7058.322298] Key type .llcrypt registered [ 7058.584308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7058.599154] alg: No test for adler32 (adler32-zlib) [ 7059.550495] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7059.730196] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7061.375537] Key type lgssc registered [ 7062.154453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7068.849278] Lustre: DEBUG MARKER: Iteration 22 [ 7069.054457] LustreError: 364287:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7069.057184] LustreError: 364290:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7069.060983] LustreError: 364287:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7070.205986] Lustre: Mounted lustre-client [ 7071.068019] LustreError: 364390:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75da1e1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7071.083137] LustreError: 364390:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7071.106922] Lustre: Unmounted lustre-client [ 7072.717584] Key type lgssc unregistered [ 7072.911760] LNet: 364638:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7072.918836] LNetError: 364638:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7072.934094] LNet: Removed LNI 192.168.202.5@tcp [ 7073.368284] Key type .llcrypt unregistered [ 7073.370078] Key type ._llcrypt unregistered [ 7073.782093] Key type ._llcrypt registered [ 7073.786299] Key type .llcrypt registered [ 7074.074629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7074.082831] alg: No test for adler32 (adler32-zlib) [ 7075.055419] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7075.191285] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7076.799141] Key type lgssc registered [ 7077.453441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7082.921391] Lustre: DEBUG MARKER: Iteration 23 [ 7083.080593] LustreError: 365466:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7083.080777] LustreError: 365467:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7083.093796] LustreError: 365466:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 7084.192399] Lustre: Mounted lustre-client [ 7084.886542] LustreError: 365575:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c6258000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7084.899712] LustreError: 365575:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7084.930043] Lustre: Unmounted lustre-client [ 7084.932192] Lustre: Skipped 1 previous similar message [ 7086.497599] Key type lgssc unregistered [ 7086.653538] LNet: 365818:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7086.657299] LNetError: 365818:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7086.669906] LNet: Removed LNI 192.168.202.5@tcp [ 7087.030153] Key type .llcrypt unregistered [ 7087.032664] Key type ._llcrypt unregistered [ 7087.436784] Key type ._llcrypt registered [ 7087.438404] Key type .llcrypt registered [ 7087.711466] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7087.718518] alg: No test for adler32 (adler32-zlib) [ 7088.645402] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7088.769531] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7090.383250] Key type lgssc registered [ 7091.049505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7097.108388] Lustre: DEBUG MARKER: Iteration 24 [ 7097.277310] LustreError: 366636:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7097.279038] LustreError: 366638:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7097.286222] LustreError: 366636:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7098.409202] Lustre: Mounted lustre-client [ 7098.412376] Lustre: Skipped 1 previous similar message [ 7099.217885] LustreError: 366747:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9e87000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7099.229378] LustreError: 366747:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7099.256513] Lustre: Unmounted lustre-client [ 7100.894537] Key type lgssc unregistered [ 7101.054383] LNet: 366989:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7101.058741] LNetError: 366989:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7101.068130] LNet: Removed LNI 192.168.202.5@tcp [ 7101.438219] Key type .llcrypt unregistered [ 7101.439594] Key type ._llcrypt unregistered [ 7101.791689] Key type ._llcrypt registered [ 7101.794150] Key type .llcrypt registered [ 7102.072726] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7102.080712] alg: No test for adler32 (adler32-zlib) [ 7103.021733] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7103.146180] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7104.767251] Key type lgssc registered [ 7105.430670] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7111.508947] Lustre: DEBUG MARKER: Iteration 25 [ 7111.704927] LustreError: 367807:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7111.707116] LustreError: 367808:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7111.712752] LustreError: 367807:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7112.836543] Lustre: Mounted lustre-client [ 7112.839532] Lustre: Skipped 1 previous similar message [ 7113.610618] LustreError: 367916:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75da139000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7113.618246] LustreError: 367916:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7113.631834] LustreError: 367914:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7113.663985] Lustre: Unmounted lustre-client [ 7115.228436] Key type lgssc unregistered [ 7115.426569] LNet: 368157:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7115.434205] LNetError: 368157:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7115.445108] LNet: Removed LNI 192.168.202.5@tcp [ 7115.850176] Key type .llcrypt unregistered [ 7115.852635] Key type ._llcrypt unregistered [ 7116.262702] Key type ._llcrypt registered [ 7116.264587] Key type .llcrypt registered [ 7116.499572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7116.511144] alg: No test for adler32 (adler32-zlib) [ 7117.474130] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7117.614282] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7119.231167] Key type lgssc registered [ 7119.901182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7126.900625] Lustre: DEBUG MARKER: Iteration 26 [ 7127.108413] LustreError: 368975:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7127.109681] LustreError: 368976:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7127.117299] LustreError: 368975:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7128.253353] Lustre: Mounted lustre-client [ 7128.255851] Lustre: Skipped 1 previous similar message [ 7129.090890] LustreError: 369078:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ccd56800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7129.101364] LustreError: 369078:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7129.132295] Lustre: Unmounted lustre-client [ 7131.122858] Key type lgssc unregistered [ 7131.329594] LNet: 369326:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7131.336356] LNetError: 369326:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7131.366035] LNet: Removed LNI 192.168.202.5@tcp [ 7131.869213] Key type .llcrypt unregistered [ 7131.873572] Key type ._llcrypt unregistered [ 7132.314405] Key type ._llcrypt registered [ 7132.315800] Key type .llcrypt registered [ 7132.673748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7132.683927] alg: No test for adler32 (adler32-zlib) [ 7133.681958] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7133.838884] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7135.455198] Key type lgssc registered [ 7136.263961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7143.027658] Lustre: DEBUG MARKER: Iteration 27 [ 7143.282460] LustreError: 370144:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7143.282572] LustreError: 370145:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7143.290516] LustreError: 370144:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7144.456210] Lustre: Mounted lustre-client [ 7144.457759] Lustre: Skipped 1 previous similar message [ 7145.191345] LustreError: 370247:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ccd86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7145.201376] LustreError: 370247:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7145.226551] Lustre: Unmounted lustre-client [ 7146.801499] Key type lgssc unregistered [ 7146.961252] LNet: 370493:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7146.970020] LNetError: 370493:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7146.984647] LNet: Removed LNI 192.168.202.5@tcp [ 7147.439165] Key type .llcrypt unregistered [ 7147.441086] Key type ._llcrypt unregistered [ 7147.831568] Key type ._llcrypt registered [ 7147.836994] Key type .llcrypt registered [ 7148.137735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7148.146245] alg: No test for adler32 (adler32-zlib) [ 7149.086378] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7149.247764] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7150.871178] Key type lgssc registered [ 7151.692238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7158.883624] Lustre: DEBUG MARKER: Iteration 28 [ 7159.091247] LustreError: 371311:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7159.095182] LustreError: 371312:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7159.099974] LustreError: 371311:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7160.250789] Lustre: Mounted lustre-client [ 7161.069909] LustreError: 371421:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cc2f0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7161.083478] LustreError: 371421:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7161.129210] Lustre: Unmounted lustre-client [ 7162.906559] Key type lgssc unregistered [ 7163.081667] LNet: 371663:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7163.085853] LNetError: 371663:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7163.096055] LNet: Removed LNI 192.168.202.5@tcp [ 7163.485220] Key type .llcrypt unregistered [ 7163.487067] Key type ._llcrypt unregistered [ 7163.885816] Key type ._llcrypt registered [ 7163.890363] Key type .llcrypt registered [ 7164.141587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7164.149584] alg: No test for adler32 (adler32-zlib) [ 7165.070663] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7165.235224] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7166.855201] Key type lgssc registered [ 7167.632570] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7174.004631] Lustre: DEBUG MARKER: Iteration 29 [ 7174.230912] LustreError: 372483:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7174.234597] LustreError: 372484:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7174.239773] LustreError: 372483:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 7175.376918] Lustre: Mounted lustre-client [ 7175.378981] Lustre: Skipped 1 previous similar message [ 7176.199489] LustreError: 372588:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9ec9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7176.215744] LustreError: 372588:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7176.241722] Lustre: Unmounted lustre-client [ 7178.016424] Key type lgssc unregistered [ 7178.231986] LNet: 372831:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7178.237104] LNetError: 372831:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7178.253740] LNet: Removed LNI 192.168.202.5@tcp [ 7178.662157] Key type .llcrypt unregistered [ 7178.664056] Key type ._llcrypt unregistered [ 7179.083729] Key type ._llcrypt registered [ 7179.088175] Key type .llcrypt registered [ 7179.316306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7179.326262] alg: No test for adler32 (adler32-zlib) [ 7180.321869] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7180.467274] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7182.122808] Key type lgssc registered [ 7183.176282] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7190.695345] Lustre: DEBUG MARKER: Iteration 30 [ 7190.990597] LustreError: 373651:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7190.995095] LustreError: 373650:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7191.006737] LustreError: 373651:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7192.199196] Lustre: Mounted lustre-client [ 7193.047800] LustreError: 373757:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f5bdb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7193.056279] LustreError: 373757:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7193.066445] LustreError: 373758:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7193.072201] LustreError: 373758:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7193.094981] Lustre: Unmounted lustre-client [ 7195.079369] Key type lgssc unregistered [ 7195.258828] LNet: 373999:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7195.266098] LNetError: 373999:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7195.317663] LNet: Removed LNI 192.168.202.5@tcp [ 7195.810422] Key type .llcrypt unregistered [ 7195.812229] Key type ._llcrypt unregistered [ 7196.293252] Key type ._llcrypt registered [ 7196.294880] Key type .llcrypt registered [ 7196.474187] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7196.484353] alg: No test for adler32 (adler32-zlib) [ 7197.474840] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7197.649614] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7199.295265] Key type lgssc registered [ 7200.065115] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7207.523055] Lustre: DEBUG MARKER: Iteration 31 [ 7207.771621] LustreError: 374814:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7207.772025] LustreError: 374822:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7207.785196] LustreError: 374814:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 7208.952754] Lustre: Mounted lustre-client [ 7209.683077] LustreError: 374924:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9eca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7209.691598] LustreError: 374924:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7209.702797] LustreError: 374927:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7209.707070] LustreError: 374927:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7209.740460] Lustre: Unmounted lustre-client [ 7211.538931] Key type lgssc unregistered [ 7211.740728] LNet: 375165:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7211.752681] LNetError: 375165:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7211.784180] LNet: Removed LNI 192.168.202.5@tcp [ 7212.244443] Key type .llcrypt unregistered [ 7212.247591] Key type ._llcrypt unregistered [ 7212.794845] Key type ._llcrypt registered [ 7212.796710] Key type .llcrypt registered [ 7213.171144] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7213.187768] alg: No test for adler32 (adler32-zlib) [ 7214.158658] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7214.313269] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7215.951811] Key type lgssc registered [ 7216.968931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7225.120452] Lustre: DEBUG MARKER: Iteration 32 [ 7225.370975] LustreError: 375985:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7225.375648] LustreError: 375986:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7225.395660] LustreError: 375985:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 7227.606202] Lustre: Mounted lustre-client [ 7228.629388] LustreError: 376092:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9ecd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7228.646917] LustreError: 376092:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7228.672139] Lustre: Unmounted lustre-client [ 7230.742588] Key type lgssc unregistered [ 7230.950330] LNet: 376334:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7230.957671] LNetError: 376334:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7231.976153] LNet: Removed LNI 192.168.202.5@tcp [ 7232.542997] Key type .llcrypt unregistered [ 7232.544724] Key type ._llcrypt unregistered [ 7233.056219] Key type ._llcrypt registered [ 7233.062798] Key type .llcrypt registered [ 7233.388419] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7233.399713] alg: No test for adler32 (adler32-zlib) [ 7234.453253] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7234.668923] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7236.359184] Key type lgssc registered [ 7237.274333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7244.308741] Lustre: DEBUG MARKER: Iteration 33 [ 7244.572672] LustreError: 377153:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7244.576091] LustreError: 377154:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7244.580838] LustreError: 377153:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 7245.793661] Lustre: Mounted lustre-client [ 7246.717202] LustreError: 377260:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c8d90800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7246.726654] LustreError: 377260:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7246.732907] LustreError: 377262:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7246.766177] Lustre: Unmounted lustre-client [ 7248.665481] Key type lgssc unregistered [ 7248.869855] LNet: 377502:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7248.873961] LNetError: 377502:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7248.885902] LNet: Removed LNI 192.168.202.5@tcp [ 7249.419135] Key type .llcrypt unregistered [ 7249.421677] Key type ._llcrypt unregistered [ 7249.968837] Key type ._llcrypt registered [ 7249.972727] Key type .llcrypt registered [ 7250.208774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7250.218444] alg: No test for adler32 (adler32-zlib) [ 7251.219537] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7251.380257] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7253.039358] Key type lgssc registered [ 7254.118470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7261.286784] Lustre: DEBUG MARKER: Iteration 34 [ 7261.471261] LustreError: 378317:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7261.471902] LustreError: 378320:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7261.484674] LustreError: 378317:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 7262.624623] Lustre: Mounted lustre-client [ 7263.513236] LustreError: 378424:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75fe223000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7263.527513] LustreError: 378424:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7263.559154] Lustre: Unmounted lustre-client [ 7265.368394] Key type lgssc unregistered [ 7265.566391] LNet: 378672:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7265.569961] LNetError: 378672:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7265.580149] LNet: Removed LNI 192.168.202.5@tcp [ 7266.019212] Key type .llcrypt unregistered [ 7266.021788] Key type ._llcrypt unregistered [ 7266.385651] Key type ._llcrypt registered [ 7266.390382] Key type .llcrypt registered [ 7266.650382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7266.665710] alg: No test for adler32 (adler32-zlib) [ 7267.606612] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7267.763502] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7269.407240] Key type lgssc registered [ 7270.342953] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7278.144094] Lustre: DEBUG MARKER: Iteration 35 [ 7278.328758] LustreError: 379489:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7278.328806] LustreError: 379488:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7278.337623] LustreError: 379489:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7279.531194] Lustre: Mounted lustre-client [ 7280.526088] LustreError: 379599:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ca46d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7280.551171] LustreError: 379599:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7280.554928] LustreError: 379599:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7280.583664] Lustre: Unmounted lustre-client [ 7282.440729] Key type lgssc unregistered [ 7282.635650] LNet: 379841:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7282.641639] LNetError: 379841:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7282.669491] LNet: Removed LNI 192.168.202.5@tcp [ 7283.210149] Key type .llcrypt unregistered [ 7283.213782] Key type ._llcrypt unregistered [ 7283.669678] Key type ._llcrypt registered [ 7283.672670] Key type .llcrypt registered [ 7284.094382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7284.103896] alg: No test for adler32 (adler32-zlib) [ 7285.141449] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7285.328539] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7286.967189] Key type lgssc registered [ 7287.924242] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7295.299296] Lustre: DEBUG MARKER: Iteration 36 [ 7295.540056] LustreError: 380663:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7295.540967] LustreError: 380664:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7295.555127] LustreError: 380663:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 7296.736909] Lustre: Mounted lustre-client [ 7296.743871] Lustre: Skipped 1 previous similar message [ 7297.695105] LustreError: 380764:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c6601800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7297.711634] LustreError: 380764:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7297.737502] Lustre: Unmounted lustre-client [ 7299.583307] Key type lgssc unregistered [ 7299.786450] LNet: 381013:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7299.792596] LNetError: 381013:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7299.809029] LNet: Removed LNI 192.168.202.5@tcp [ 7300.352193] Key type .llcrypt unregistered [ 7300.353947] Key type ._llcrypt unregistered [ 7300.878521] Key type ._llcrypt registered [ 7300.880444] Key type .llcrypt registered [ 7301.083833] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7301.096995] alg: No test for adler32 (adler32-zlib) [ 7302.077417] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7302.238732] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7303.903170] Key type lgssc registered [ 7304.702471] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7311.460928] Lustre: DEBUG MARKER: Iteration 37 [ 7311.681840] LustreError: 381828:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7311.685580] LustreError: 381836:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7311.696089] LustreError: 381828:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 7312.883579] Lustre: Mounted lustre-client [ 7312.887154] Lustre: Skipped 1 previous similar message [ 7313.683108] LustreError: 381936:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cbb1a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7313.699616] LustreError: 381936:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7313.725099] Lustre: Unmounted lustre-client [ 7315.604464] Key type lgssc unregistered [ 7315.854483] LNet: 382184:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7315.859346] LNetError: 382184:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7315.872774] LNet: Removed LNI 192.168.202.5@tcp [ 7316.352374] Key type .llcrypt unregistered [ 7316.357184] Key type ._llcrypt unregistered [ 7316.906201] Key type ._llcrypt registered [ 7316.907688] Key type .llcrypt registered [ 7317.239482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7317.255252] alg: No test for adler32 (adler32-zlib) [ 7318.272025] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7318.434854] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7320.111190] Key type lgssc registered [ 7320.826496] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7327.871813] Lustre: DEBUG MARKER: Iteration 38 [ 7328.113283] LustreError: 383002:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7328.113312] LustreError: 383003:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7328.122231] LustreError: 383002:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7329.265154] Lustre: Mounted lustre-client [ 7330.278643] LustreError: 383105:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cc2f3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7330.296836] LustreError: 383105:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7330.320599] Lustre: Unmounted lustre-client [ 7331.922628] Key type lgssc unregistered [ 7332.104836] LNet: 383354:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7332.110755] LNetError: 383354:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7332.123229] LNet: Removed LNI 192.168.202.5@tcp [ 7332.563158] Key type .llcrypt unregistered [ 7332.565716] Key type ._llcrypt unregistered [ 7333.056357] Key type ._llcrypt registered [ 7333.060079] Key type .llcrypt registered [ 7333.331797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7333.339564] alg: No test for adler32 (adler32-zlib) [ 7334.274873] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7334.433992] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7336.063700] Key type lgssc registered [ 7336.881318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7343.291949] Lustre: DEBUG MARKER: Iteration 39 [ 7343.468243] LustreError: 384171:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7343.470072] LustreError: 384172:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7343.477151] LustreError: 384171:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7344.594636] Lustre: Mounted lustre-client [ 7345.248122] LustreError: 384282:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e7601960000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7345.254491] LustreError: 384282:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7345.261194] LustreError: 384279:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7345.265025] LustreError: 384279:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7345.283207] Lustre: Unmounted lustre-client [ 7346.776564] Key type lgssc unregistered [ 7346.949862] LNet: 384521:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7346.953189] LNetError: 384521:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7346.967872] LNet: Removed LNI 192.168.202.5@tcp [ 7347.345162] Key type .llcrypt unregistered [ 7347.346884] Key type ._llcrypt unregistered [ 7347.685097] Key type ._llcrypt registered [ 7347.686730] Key type .llcrypt registered [ 7347.991363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7348.004375] alg: No test for adler32 (adler32-zlib) [ 7348.927349] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7349.063377] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7350.671164] Key type lgssc registered [ 7351.451356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7357.897226] Lustre: DEBUG MARKER: Iteration 40 [ 7358.075441] LustreError: 385337:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7358.075576] LustreError: 385338:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7358.083642] LustreError: 385337:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7359.250926] Lustre: Mounted lustre-client [ 7359.253070] Lustre: Skipped 1 previous similar message [ 7360.048841] LustreError: 385447:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7360.059735] LustreError: 385447:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7360.090089] Lustre: Unmounted lustre-client [ 7361.879788] Key type lgssc unregistered [ 7362.088773] LNet: 385689:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7362.095067] LNetError: 385689:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7362.112438] LNet: Removed LNI 192.168.202.5@tcp [ 7362.617155] Key type .llcrypt unregistered [ 7362.620985] Key type ._llcrypt unregistered [ 7363.068322] Key type ._llcrypt registered [ 7363.073051] Key type .llcrypt registered [ 7363.283462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7363.299725] alg: No test for adler32 (adler32-zlib) [ 7364.229736] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7364.373850] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7365.991176] Key type lgssc registered [ 7366.694467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7372.872900] Lustre: DEBUG MARKER: Iteration 41 [ 7373.088638] LustreError: 386507:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7373.093647] LustreError: 386508:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7373.098285] LustreError: 386507:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7374.248913] Lustre: Mounted lustre-client [ 7374.995885] LustreError: 386617:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75e9e15800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7375.005786] LustreError: 386617:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7375.029081] Lustre: Unmounted lustre-client [ 7376.522685] Key type lgssc unregistered [ 7376.707623] LNet: 386857:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7376.712835] LNetError: 386857:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7376.724869] LNet: Removed LNI 192.168.202.5@tcp [ 7377.074147] Key type .llcrypt unregistered [ 7377.075393] Key type ._llcrypt unregistered [ 7377.680198] Key type ._llcrypt registered [ 7377.686905] Key type .llcrypt registered [ 7377.839791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7377.857189] alg: No test for adler32 (adler32-zlib) [ 7378.795951] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7378.927780] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7380.551184] Key type lgssc registered [ 7381.293295] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7387.247520] Lustre: DEBUG MARKER: Iteration 42 [ 7387.439103] LustreError: 387675:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7387.440789] LustreError: 387676:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7387.454164] LustreError: 387675:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 7388.595803] Lustre: Mounted lustre-client [ 7388.597257] Lustre: Skipped 1 previous similar message [ 7389.458236] LustreError: 387785:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ccd54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7389.467064] LustreError: 387785:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7389.469767] LustreError: 387785:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7389.511110] Lustre: Unmounted lustre-client [ 7390.753613] Key type lgssc unregistered [ 7390.895325] LNet: 388028:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7390.898817] LNetError: 388028:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7390.905557] LNet: Removed LNI 192.168.202.5@tcp [ 7391.219066] Key type .llcrypt unregistered [ 7391.220506] Key type ._llcrypt unregistered [ 7391.569298] Key type ._llcrypt registered [ 7391.572377] Key type .llcrypt registered [ 7391.783880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7391.794108] alg: No test for adler32 (adler32-zlib) [ 7392.701451] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7392.817900] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7394.431226] Key type lgssc registered [ 7395.052386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7399.714709] Lustre: DEBUG MARKER: Iteration 43 [ 7399.858196] LustreError: 388846:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7399.858217] LustreError: 388845:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7399.865889] LustreError: 388846:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7400.995761] Lustre: Mounted lustre-client [ 7400.995761] Lustre: Mounted lustre-client [ 7401.699448] LustreError: 388953:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ca46a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7401.712363] LustreError: 388955:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7401.715929] LustreError: 388955:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7401.741116] Lustre: Unmounted lustre-client [ 7403.188751] Key type lgssc unregistered [ 7403.355914] LNet: 389197:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7403.359957] LNetError: 389197:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7403.370136] LNet: Removed LNI 192.168.202.5@tcp [ 7403.690468] Key type .llcrypt unregistered [ 7403.694598] Key type ._llcrypt unregistered [ 7404.051631] Key type ._llcrypt registered [ 7404.053219] Key type .llcrypt registered [ 7404.259169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7404.268874] alg: No test for adler32 (adler32-zlib) [ 7405.181639] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7405.314557] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7406.936072] Key type lgssc registered [ 7407.565775] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7413.266760] Lustre: DEBUG MARKER: Iteration 44 [ 7413.451964] LustreError: 390015:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7413.453180] LustreError: 390016:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7413.460852] LustreError: 390015:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7414.597806] Lustre: Mounted lustre-client [ 7414.601831] Lustre: Skipped 1 previous similar message [ 7415.427169] LustreError: 390125:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c2d3d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7415.432764] LustreError: 390125:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7415.449234] LustreError: 390124:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7415.451907] LustreError: 390124:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7415.474718] Lustre: Unmounted lustre-client [ 7417.095768] Key type lgssc unregistered [ 7417.265195] LNet: 390365:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7417.268804] LNetError: 390365:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7417.285262] LNet: Removed LNI 192.168.202.5@tcp [ 7417.695227] Key type .llcrypt unregistered [ 7417.697300] Key type ._llcrypt unregistered [ 7418.061315] Key type ._llcrypt registered [ 7418.062817] Key type .llcrypt registered [ 7418.286306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7418.294373] alg: No test for adler32 (adler32-zlib) [ 7419.286091] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7419.425895] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7421.047201] Key type lgssc registered [ 7421.661470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7427.709561] Lustre: DEBUG MARKER: Iteration 45 [ 7427.965223] LustreError: 391183:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7427.967711] LustreError: 391184:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7427.977182] LustreError: 391183:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7429.142390] Lustre: Mounted lustre-client [ 7429.144669] Lustre: Skipped 1 previous similar message [ 7429.962901] LustreError: 391286:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75ca46d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7429.982360] LustreError: 391286:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7430.008196] Lustre: Unmounted lustre-client [ 7431.456581] Key type lgssc unregistered [ 7431.631636] LNet: 391534:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7431.636168] LNetError: 391534:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7431.643184] LNet: Removed LNI 192.168.202.5@tcp [ 7431.998255] Key type .llcrypt unregistered [ 7432.000112] Key type ._llcrypt unregistered [ 7432.393746] Key type ._llcrypt registered [ 7432.394913] Key type .llcrypt registered [ 7432.565251] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7432.572039] alg: No test for adler32 (adler32-zlib) [ 7433.508485] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7433.663459] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7435.288811] Key type lgssc registered [ 7435.939074] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7441.302435] Lustre: DEBUG MARKER: Iteration 46 [ 7441.468217] LustreError: 392353:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7441.468293] LustreError: 392352:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7441.475960] LustreError: 392353:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7442.598120] Lustre: Mounted lustre-client [ 7442.600732] Lustre: Skipped 1 previous similar message [ 7443.347476] LustreError: 392461:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7443.358692] LustreError: 392461:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7443.396083] Lustre: Unmounted lustre-client [ 7444.990614] Key type lgssc unregistered [ 7445.179543] LNet: 392705:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7445.187788] LNetError: 392705:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7445.205917] LNet: Removed LNI 192.168.202.5@tcp [ 7445.593245] Key type .llcrypt unregistered [ 7445.594960] Key type ._llcrypt unregistered [ 7446.036282] Key type ._llcrypt registered [ 7446.054346] Key type .llcrypt registered [ 7446.208844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7446.217974] alg: No test for adler32 (adler32-zlib) [ 7447.126913] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7447.241206] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7448.839194] Key type lgssc registered [ 7449.642516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7455.742516] Lustre: DEBUG MARKER: Iteration 47 [ 7455.947476] LustreError: 393522:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7455.948384] LustreError: 393523:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7455.956896] LustreError: 393522:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 7457.079658] Lustre: Mounted lustre-client [ 7457.836894] LustreError: 393629:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75cccdf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7457.845631] LustreError: 393629:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 7457.852729] LustreError: 393630:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7457.858845] LustreError: 393630:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7457.877604] Lustre: Unmounted lustre-client [ 7459.301883] Key type lgssc unregistered [ 7459.482942] LNet: 393870:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7459.487720] LNetError: 393870:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7459.506786] LNet: Removed LNI 192.168.202.5@tcp [ 7459.952238] Key type .llcrypt unregistered [ 7459.954125] Key type ._llcrypt unregistered [ 7460.303017] Key type ._llcrypt registered [ 7460.304637] Key type .llcrypt registered [ 7460.550204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7460.561387] alg: No test for adler32 (adler32-zlib) [ 7461.502409] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7461.634980] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7463.271219] Key type lgssc registered [ 7463.953469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7469.342362] Lustre: DEBUG MARKER: Iteration 48 [ 7469.494443] LustreError: 394693:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7469.495287] LustreError: 394694:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7469.503164] LustreError: 394693:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7470.633674] Lustre: Mounted lustre-client [ 7470.636741] Lustre: Skipped 1 previous similar message [ 7471.380302] LustreError: 394795:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75fa795000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7471.397193] LustreError: 394795:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 7471.419414] Lustre: Unmounted lustre-client [ 7472.876511] Key type lgssc unregistered [ 7473.046161] LNet: 395044:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7473.052389] LNetError: 395044:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7473.061541] LNet: Removed LNI 192.168.202.5@tcp [ 7473.501240] Key type .llcrypt unregistered [ 7473.503153] Key type ._llcrypt unregistered [ 7473.915616] Key type ._llcrypt registered [ 7473.917313] Key type .llcrypt registered [ 7474.148965] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7474.157153] alg: No test for adler32 (adler32-zlib) [ 7475.090920] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7475.203707] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7476.823277] Key type lgssc registered [ 7477.590484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7484.097422] Lustre: DEBUG MARKER: Iteration 49 [ 7484.283955] LustreError: 395863:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7484.287382] LustreError: 395864:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7484.295665] LustreError: 395863:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 7485.459916] Lustre: Mounted lustre-client [ 7485.461190] Lustre: Skipped 1 previous similar message [ 7486.096126] LustreError: 395974:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f5bdb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7486.106897] LustreError: 395974:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7486.133530] Lustre: Unmounted lustre-client [ 7487.519181] Key type lgssc unregistered [ 7487.666668] LNet: 396216:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7487.670251] LNetError: 396216:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7487.681616] LNet: Removed LNI 192.168.202.5@tcp [ 7488.057357] Key type .llcrypt unregistered [ 7488.059206] Key type ._llcrypt unregistered [ 7488.419392] Key type ._llcrypt registered [ 7488.420926] Key type .llcrypt registered [ 7488.626644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7488.633759] alg: No test for adler32 (adler32-zlib) [ 7489.540926] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7489.653260] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7491.271251] Key type lgssc registered [ 7491.867438] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7497.781690] Lustre: DEBUG MARKER: Iteration 50 [ 7497.925686] LustreError: 397033:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7497.927800] LustreError: 397040:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7497.932448] LustreError: 397033:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7499.068905] Lustre: Mounted lustre-client [ 7499.997136] LustreError: 397142:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c737e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7500.014410] LustreError: 397142:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7500.039211] Lustre: Unmounted lustre-client [ 7501.690074] Key type lgssc unregistered [ 7501.851957] LNet: 397387:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7501.856762] LNetError: 397387:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7501.867872] LNet: Removed LNI 192.168.202.5@tcp [ 7502.240187] Key type .llcrypt unregistered [ 7502.241715] Key type ._llcrypt unregistered [ 7502.620570] Key type ._llcrypt registered [ 7502.622486] Key type .llcrypt registered [ 7502.885581] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7502.894333] alg: No test for adler32 (adler32-zlib) [ 7503.849823] Lustre: Lustre: Build Version: 2.16.58_53_g2e3f3ed [ 7503.989710] LNet: Added LNI 192.168.202.5@tcp [8/256/0/180] [ 7505.599151] Key type lgssc registered [ 7506.223287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7512.306097] Lustre: Mounted lustre-client [ 7515.729688] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 16:08:22 (1758744502) [ 7532.511223] Lustre: 398719:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758744503/real 1758744503] req@ffff9e75f34c8380 x1844177263403776/t0(0) o36->lustre-MDT0000-mdc-ffff9e75f5bd9800@192.168.202.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1758744519 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7532.523364] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection to lustre-MDT0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7532.551286] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 7548.895253] Lustre: 398719:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758744519/real 1758744519] req@ffff9e75f34c8380 x1844177263403776/t0(0) o36->lustre-MDT0000-mdc-ffff9e75f5bd9800@192.168.202.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1758744535 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7548.907399] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection to lustre-MDT0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7548.923363] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 7564.255912] Lustre: 398719:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758744535/real 1758744535] req@ffff9e75f34c8380 x1844177263403776/t0(0) o36->lustre-MDT0000-mdc-ffff9e75f5bd9800@192.168.202.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1758744551 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7564.271652] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection to lustre-MDT0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7564.288102] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 7580.640650] Lustre: 398719:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758744551/real 1758744551] req@ffff9e75f34c8380 x1844177263403776/t0(0) o36->lustre-MDT0000-mdc-ffff9e75f5bd9800@192.168.202.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1758744567 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7580.659849] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection to lustre-MDT0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7580.695792] Lustre: lustre-MDT0000-mdc-ffff9e75f5bd9800: Connection restored to 192.168.202.105@tcp (at 192.168.202.105@tcp) [ 7583.354884] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 16:09:29 (1758744569) [ 7584.243459] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 7585.339453] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 16:09:31 (1758744571) [ 7586.214877] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 7587.193583] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 16:09:33 (1758744573) [ 7591.408477] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 16:09:37 (1758744577) [ 7592.434371] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 7593.591742] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 16:09:39 (1758744579) [ 7594.398356] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 7595.373481] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 16:09:41 (1758744581) [ 7596.215219] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 7597.189705] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 16:09:43 (1758744583) [ 7616.479204] Lustre: 397578:0:(client.c:2464:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1758744587/real 1758744587] req@ffff9e75f34c8700 x1844177263428992/t0(0) o4->lustre-OST0000-osc-ffff9e75f5bd9800@192.168.202.105@tcp:6/4 lens 4584/448 e 0 to 1 dl 1758744603 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 7616.479368] Lustre: lustre-OST0000-osc-ffff9e75f5bd9800: Connection to lustre-OST0000 (at 192.168.202.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7616.491847] Lustre: 397578:0:(client.c:2464:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 7707.001818] Lustre: DEBUG MARKER: oleg205-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9e75c9ecc000.ost_server_uuid 50 [ 7707.811166] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9e75c9ecc000.ost_server_uuid in IDLE state after 0 sec [ 7708.715863] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7709.709961] Lustre: DEBUG MARKER: == sanityn test complete, duration 7483 sec ============== 16:11:35 (1758744695) [ 7710.617236] Lustre: DEBUG MARKER: === sanityn: start cleanup 16:11:36 (1758744696) === [ 7832.707899] LustreError: 402826:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75c9ecc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7832.712249] LustreError: 402826:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7832.723093] Lustre: Unmounted lustre-client [ 7834.200599] Lustre: DEBUG MARKER: === sanityn: finish cleanup 16:13:40 (1758744820) === [ 7834.592075] LustreError: 403126:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9e75f5bd9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7834.597882] LustreError: 403126:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 7834.608531] LustreError: 403126:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7834.611155] LustreError: 403126:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 7834.637729] Lustre: Unmounted lustre-client [ 7848.540952] Key type lgssc unregistered [ 7848.670510] LNet: 403607:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7848.673939] LNetError: 403607:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7848.684863] LNet: Removed LNI 192.168.202.5@tcp [ 7848.963244] Key type .llcrypt unregistered [ 7848.965103] Key type ._llcrypt unregistered