[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 418259961 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002392] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004011] kvm-guest: setup PV IPIs [ 0.006882] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.010123] LSM: Security Framework initializing [ 0.011046] Yama: becoming mindful. [ 0.012031] SELinux: Initializing. [ 0.012889] *** VALIDATE selinux *** [ 0.021305] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025630] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026150] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027107] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028103] *** VALIDATE tmpfs *** [ 0.030183] *** VALIDATE proc *** [ 0.031225] *** VALIDATE cgroup *** [ 0.032005] *** VALIDATE cgroup2 *** [ 0.033165] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034120] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035005] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036020] Spectre V2 : User space: Vulnerable [ 0.037005] Speculative Store Bypass: Vulnerable [ 0.040204] debug: unmapping init [mem 0xffffffffa7259000-0xffffffffa7260fff] [ 0.044217] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045900] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046030] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048012] ... generic registers: 4 [ 0.048985] ... value mask: 0000ffffffffffff [ 0.049021] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.050929] ... event mask: 000000070000000f [ 0.052325] rcu: Hierarchical SRCU implementation. [ 0.054637] smp: Bringing up secondary CPUs ... [ 0.055647] x86: Booting SMP configuration: [ 0.056026] .... node #0, CPUs: #1 #2 #3 [ 0.060020] smp: Brought up 1 node, 4 CPUs [ 0.062025] smpboot: Max logical packages: 1 [ 0.063020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.229481] node 0 deferred pages initialised in 164ms [ 0.231186] devtmpfs: initialized [ 0.232380] x86/mm: Memory block size: 128MB [ 0.234879] gcov: version magic: 0x41383552 [ 0.236410] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.239092] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.241313] pinctrl core: initialized pinctrl subsystem [ 0.244203] [ 0.244818] ************************************************************* [ 0.247018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.249013] ** ** [ 0.252015] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.254028] ** ** [ 0.257015] ** This means that this kernel is built to expose internal ** [ 0.259014] ** IOMMU data structures, which may compromise security on ** [ 0.261031] ** your system. ** [ 0.263015] ** ** [ 0.265011] ** If you see this message and you are not debugging the ** [ 0.267011] ** kernel, report this immediately to your vendor! ** [ 0.269011] ** ** [ 0.271014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.274017] ************************************************************* [ 0.276788] NET: Registered protocol family 16 [ 0.278470] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.281061] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.284069] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.287091] cpuidle: using governor menu [ 0.289000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.291480] PCI: Using configuration type 1 for base access [ 0.293149] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.302232] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.305092] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.308159] cryptd: max_cpu_qlen set to 1000 [ 0.312255] ACPI: Added _OSI(Module Device) [ 0.314013] ACPI: Added _OSI(Processor Device) [ 0.315013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.316013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.319640] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.324478] ACPI: Interpreter enabled [ 0.325000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.327012] ACPI: Using IOAPIC for interrupt routing [ 0.328110] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.331458] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.340000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.342059] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.345023] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.347067] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.351147] acpiphp: Slot [2] registered [ 0.352094] acpiphp: Slot [5] registered [ 0.354096] acpiphp: Slot [6] registered [ 0.355076] acpiphp: Slot [3] registered [ 0.356064] acpiphp: Slot [4] registered [ 0.358168] acpiphp: Slot [7] registered [ 0.359176] acpiphp: Slot [8] registered [ 0.361060] acpiphp: Slot [9] registered [ 0.362052] acpiphp: Slot [10] registered [ 0.363091] acpiphp: Slot [11] registered [ 0.364083] acpiphp: Slot [12] registered [ 0.365100] acpiphp: Slot [13] registered [ 0.366085] acpiphp: Slot [14] registered [ 0.367061] acpiphp: Slot [15] registered [ 0.368059] acpiphp: Slot [16] registered [ 0.370067] acpiphp: Slot [17] registered [ 0.371082] acpiphp: Slot [18] registered [ 0.372079] acpiphp: Slot [19] registered [ 0.374128] acpiphp: Slot [20] registered [ 0.376172] acpiphp: Slot [21] registered [ 0.377087] acpiphp: Slot [22] registered [ 0.378089] acpiphp: Slot [23] registered [ 0.380082] acpiphp: Slot [24] registered [ 0.381090] acpiphp: Slot [25] registered [ 0.382120] acpiphp: Slot [26] registered [ 0.384086] acpiphp: Slot [27] registered [ 0.385083] acpiphp: Slot [28] registered [ 0.386098] acpiphp: Slot [29] registered [ 0.388082] acpiphp: Slot [30] registered [ 0.389086] acpiphp: Slot [31] registered [ 0.390056] PCI host bridge to bus 0000:00 [ 0.391016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.393023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.395028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.398026] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.401027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.403026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.406190] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.410159] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.413311] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.419873] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.423060] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.424014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.426012] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.428063] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.429499] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.432553] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.434031] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.436661] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.439832] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.448015] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.452592] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.457515] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.462014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.471019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.483020] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.494061] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.500022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.505028] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.518026] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.531100] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.533202] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.534187] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.535209] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.537181] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.540202] iommu: Default domain type: Passthrough [ 0.542341] SCSI subsystem initialized [ 0.543093] ACPI: bus type USB registered [ 0.545103] usbcore: registered new interface driver usbfs [ 0.546066] usbcore: registered new interface driver hub [ 0.548080] usbcore: registered new device driver usb [ 0.550159] pps_core: LinuxPPS API ver. 1 registered [ 0.552010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.554057] PTP clock support registered [ 0.556074] EDAC MC: Ver: 3.0.0 [ 0.557397] PCI: Using ACPI for IRQ routing [ 0.559607] NetLabel: Initializing [ 0.561013] NetLabel: domain hash size = 128 [ 0.562010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.564083] NetLabel: unlabeled traffic allowed by default [ 0.566139] vgaarb: loaded [ 0.567287] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.569018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.576052] clocksource: Switched to clocksource kvm-clock [ 0.675825] VFS: Disk quotas dquot_6.6.0 [ 0.676744] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.678412] *** VALIDATE ramfs *** [ 0.679261] *** VALIDATE hugetlbfs *** [ 0.680316] pnp: PnP ACPI init [ 0.682367] pnp: PnP ACPI: found 6 devices [ 0.699095] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.702395] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.704206] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.706194] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.708415] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.710655] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.713671] NET: Registered protocol family 2 [ 0.716158] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.720267] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.723325] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.728902] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.733485] TCP: Hash tables configured (established 65536 bind 65536) [ 0.736653] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.739765] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.742443] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.745250] NET: Registered protocol family 1 [ 0.747622] RPC: Registered named UNIX socket transport module. [ 0.749568] RPC: Registered udp transport module. [ 0.751190] RPC: Registered tcp transport module. [ 0.752727] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.754941] NET: Registered protocol family 44 [ 0.756594] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.758976] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.761841] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.763834] PCI: CLS 0 bytes, default 64 [ 0.765484] Unpacking initramfs... [ 2.054931] debug: unmapping init [mem 0xffff8c8afcc64000-0xffff8c8afffcffff] [ 2.057763] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.059174] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.060538] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.535963] Initialise system trusted keyrings [ 2.537287] Key type blacklist registered [ 2.538593] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.546532] zbud: loaded [ 2.548865] *** VALIDATE nfs *** [ 2.549660] *** VALIDATE nfs4 *** [ 2.550631] pstore: using deflate compression [ 2.552991] Platform Keyring initialized [ 2.650956] NET: Registered protocol family 38 [ 2.652589] Key type asymmetric registered [ 2.653767] Asymmetric key parser 'x509' registered [ 2.655345] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.658569] io scheduler mq-deadline registered [ 2.660364] io scheduler kyber registered [ 2.662055] io scheduler bfq registered [ 2.663261] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.664909] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.666771] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.668920] ACPI: Power Button [PWRF] [ 2.674088] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.680206] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.694817] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.720065] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.748053] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.753229] Non-volatile memory driver v1.3 [ 2.754863] Linux agpgart interface v0.103 [ 2.789239] virtio_blk virtio1: [vda] 134216 512-byte logical blocks (68.7 MB/65.5 MiB) [ 2.792229] vda: detected capacity change from 0 to 68718592 [ 2.814971] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.818125] vdb: detected capacity change from 0 to 1073741824 [ 2.829660] libphy: Fixed MDIO Bus: probed [ 2.838976] usbcore: registered new interface driver usbserial_generic [ 2.841381] usbserial: USB Serial support registered for generic [ 2.843802] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.847940] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.849765] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.852149] mousedev: PS/2 mouse device common for all mice [ 2.854928] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.855903] rtc_cmos 00:05: RTC can wake from S4 [ 2.860429] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.860815] rtc_cmos 00:05: registered as rtc0 [ 2.865111] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.865533] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.870780] intel_pstate: CPU model not supported [ 2.873689] hid: raw HID events driver (C) Jiri Kosina [ 2.876173] usbcore: registered new interface driver usbhid [ 2.878326] usbhid: USB HID core driver [ 2.880153] drop_monitor: Initializing network drop monitor service [ 2.882512] Initializing XFRM netlink socket [ 2.884367] NET: Registered protocol family 10 [ 2.887281] Segment Routing with IPv6 [ 2.888572] NET: Registered protocol family 17 [ 2.890412] mpls_gso: MPLS GSO support [ 2.895908] RAS: Correctable Errors collector initialized. [ 2.897861] AVX version of gcm_enc/dec engaged. [ 2.899487] AES CTR mode by8 optimization enabled [ 2.972541] sched_clock: Marking stable (2972514232, 0)->(3765444601, -792930369) [ 2.976242] registered taskstats version 1 [ 2.978414] Loading compiled-in X.509 certificates [ 2.980646] zswap: loaded using pool lzo/zbud [ 3.000134] Key type big_key registered [ 3.011603] Key type encrypted registered [ 3.012724] ima: No TPM chip found, activating TPM-bypass! [ 3.014573] ima: Allocated hash algorithm: sha1 [ 3.015899] ima: No architecture policies found [ 3.017065] evm: Initialising EVM extended attributes: [ 3.018347] evm: security.selinux [ 3.019216] evm: security.ima [ 3.019860] evm: security.capability [ 3.020654] evm: HMAC attrs: 0x1 [ 3.022306] rtc_cmos 00:05: setting system clock to 2026-01-30 05:45:07 UTC (1769751907) [ 3.026562] debug: unmapping init [mem 0xffffffffa8203000-0xffffffffa83fffff] [ 3.028676] debug: unmapping init [mem 0xffffffffa6f82000-0xffffffffa7258fff] [ 3.036088] Write protecting the kernel read-only data: 28672k [ 3.039605] debug: unmapping init [mem 0xffffffffa5603000-0xffffffffa57fffff] [ 3.042440] debug: unmapping init [mem 0xffffffffa5f14000-0xffffffffa5ffffff] [ 3.072346] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.079414] systemd[1]: Detected virtualization kvm. [ 3.081840] systemd[1]: Detected architecture x86-64. [ 3.083925] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.107742] systemd[1]: No hostname configured. [ 3.109520] systemd[1]: Set hostname to . [ 3.111589] random: systemd: uninitialized urandom read (16 bytes read) [ 3.113753] systemd[1]: Initializing machine ID from random generator. [ 3.167535] random: ln: uninitialized urandom read (6 bytes read) [ 3.260693] random: systemd: uninitialized urandom read (16 bytes read) [ 3.263902] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.274490] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ 3.282639] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. Starting Setup Virtual Console... [ OK ] Reached target Timers. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.895847] device-mapper: uevent: version 1.0.3 [ 3.898439] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.655618] virtio_net virtio0 ens2: renamed from eth0 [ 4.738317] scsi host0: ata_piix [ 4.759140] scsi host1: ata_piix [ 4.760789] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.763271] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.145272] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.527595] random: crng init done [ 9.528558] random: 7 urandom warning(s) missed due to ratelimiting 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... [ 9.961615] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.073829] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.318283] SELinux: Disabled at runtime. [ 11.374017] 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) [ 11.381320] systemd[1]: Detected virtualization kvm. [ 11.382821] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.822672] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.826996] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.832358] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.837425] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.840764] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.848410] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.852200] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root F[ 11.966603] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ile System. [ OK ] Reached target Paths. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. Starting Apply Kernel Variables... Starting udev Coldplug all Devices... Starting Remount Root and Kernel File Systems... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started 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 /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 12.280195] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.619617] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.660963] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.765508] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.786396] EDAC sbridge: Ver: 1.1.2 [ 13.957814] Key type dns_resolver registered [ 14.250794] NFS: Registering the id_resolver key type [ 14.252915] Key type id_resolver registered [ 14.254404] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. Starting Login Service... Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg147-client login: [ 47.936359] libcfs: loading out-of-tree module taints kernel. [ 47.993481] Key type ._llcrypt registered [ 47.996643] Key type .llcrypt registered [ 48.391417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 48.406667] alg: No test for adler32 (adler32-zlib) [ 49.898361] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [ 51.129295] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [ 53.136652] Key type lgssc registered [ 55.746842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 225.435214] Lustre: Mounted lustre-client [ 230.059871] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 247.217634] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing check_logdir /tmp/testlogs/ [ 251.361286] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: disconnect after 23s idle [ 252.295348] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing yml_node [ 257.172448] Lustre: DEBUG MARKER: Client: 2.17.50.72 [ 259.718809] Lustre: DEBUG MARKER: MDS: 2.17.50.72 [ 262.800888] Lustre: DEBUG MARKER: OSS: 2.17.50.72 [ 264.614108] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Jan 30 00:49:27 EST 2026 [ 283.641315] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 285.130972] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 286.644038] Lustre: DEBUG MARKER: === sanityn: start setup 00:49:49 (1769752189) === [ 287.750369] Lustre: Mounted lustre-client [ 292.002760] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing check_config_client /mnt/lustre [ 314.803335] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 325.494843] Lustre: DEBUG MARKER: === sanityn: finish setup 00:50:28 (1769752228) === [ 327.154828] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 00:50:30 (1769752230) [ 336.423860] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 00:50:39 (1769752239) [ 343.786166] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 00:50:46 (1769752246) [ 350.895328] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 00:50:53 (1769752253) [ 357.532774] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 00:51:00 (1769752260) [ 364.290554] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 00:51:07 (1769752267) [ 371.127884] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 00:51:14 (1769752274) [ 378.951759] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 00:51:21 (1769752281) [ 389.563826] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 00:51:32 (1769752292) [ 392.429062] hrtimer: interrupt took 4583786 ns [ 398.830386] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 00:51:41 (1769752301) [ 405.968194] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 00:51:48 (1769752308) [ 410.594440] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 21s idle [ 410.607470] Lustre: Skipped 1 previous similar message [ 413.074764] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 00:51:56 (1769752316) [ 419.534967] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 00:52:02 (1769752322) [ 426.333612] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 00:52:09 (1769752329) [ 431.073974] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 24s idle [ 432.834372] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 00:52:15 (1769752335) [ 439.340984] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 00:52:22 (1769752342) [ 445.824538] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 00:52:28 (1769752348) [ 452.409689] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 00:52:35 (1769752355) [ 459.431767] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 00:52:42 (1769752362) [ 465.384820] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 00:52:48 (1769752368) [ 472.713708] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 00:52:55 (1769752375) [ 473.501917] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 610.851907] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 00:55:14 (1769752514) [ 618.233783] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 00:55:21 (1769752521) [ 623.940672] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 00:55:27 (1769752527) [ 629.925303] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 00:55:33 (1769752533) [ 635.809252] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 00:55:39 (1769752539) [ 642.180449] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 00:55:45 (1769752545) [ 643.910702] Lustre: DEBUG MARKER: chmod [ 649.777178] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 00:55:52 (1769752552) [ 1647.728770] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 01:12:30 (1769753550) [ 1649.641560] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 22s idle [ 1782.752944] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 22s idle [ 1782.762718] Lustre: Skipped 1 previous similar message [ 1867.156567] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 01:16:10 (1769753770) [ 1966.847284] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 01:17:49 (1769753869) [ 2110.341462] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 01:20:13 (1769754013) [ 2146.276417] Lustre: lustre-OST0001-osc-ffff8c8b48479800: disconnect after 23s idle [ 2146.992399] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 01:20:49 (1769754049) [ 2155.067670] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 01:20:58 (1769754058) [ 2156.359329] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2156.479563] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2156.651733] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2156.747709] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2156.835541] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2156.917975] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.007134] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.079715] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.157535] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.237976] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.293381] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.371854] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.475978] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.556762] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.632569] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.746153] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.878686] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2157.999106] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.145557] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.225718] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.333639] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.402363] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.497841] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.572483] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.659408] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.742139] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.805044] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2158.916958] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.085201] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.251174] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.436132] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.609962] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.730992] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.838198] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2159.980074] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.064123] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.184531] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.274145] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.468643] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.606846] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.740407] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.837678] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2160.916866] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.010876] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.077829] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.175678] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.262660] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.330300] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.399211] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.492732] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.576416] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.692539] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.781644] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2161.912341] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.038893] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.129316] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.209612] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.307864] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.394986] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.451105] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.524431] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.621656] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.691251] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.780827] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.899867] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2162.959444] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.075602] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.156305] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.230649] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.291456] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.377876] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.458138] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.554452] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.656289] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.793748] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2163.929684] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.049203] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.206938] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.320327] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.433514] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.551048] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.610826] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.702682] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.813978] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2164.928554] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.008151] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.090835] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.158295] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.242821] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.305107] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.380598] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.502344] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.587153] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.685702] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.752695] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.869858] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2165.957622] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.037772] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.116902] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.175087] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.258875] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.345829] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.461976] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.559433] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.641414] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.721129] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.816773] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.911258] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2166.993372] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.082622] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.176105] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.298797] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.415162] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.508490] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.563183] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.638263] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.736960] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.842280] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2167.929964] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.002079] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.069039] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.165534] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.255400] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.318855] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.373457] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.494714] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.605329] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.725309] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.851156] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2168.932369] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.042354] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.170646] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.317918] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.407608] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.496805] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.582474] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.698296] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.802100] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2169.923197] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.039171] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.161786] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.275888] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.339276] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.385382] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.432857] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.506374] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.570871] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.648400] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.753648] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.857203] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.923128] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2170.995988] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.089169] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.184413] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.238804] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.314601] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.389783] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.460913] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.524192] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.608268] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.671439] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.731304] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.796778] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.852795] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2171.874029] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: disconnect after 21s idle [ 2171.939707] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.020794] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.091190] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.162820] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.236059] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.327341] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.410264] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.492151] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.607882] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.698398] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.805567] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.874548] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2172.961309] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.032056] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.097343] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.175099] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.243884] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.333217] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.399510] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.480694] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.538741] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.605369] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.666918] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.723251] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.787799] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.853328] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.932987] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2173.997524] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.072226] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.131857] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.312585] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.464749] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.530291] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.634277] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.724182] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.805391] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.874421] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2174.947495] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.022862] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.073435] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.154103] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.215586] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.297022] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2175.382328] rw_seq_cst_vs_d (32380): drop_caches: 3 [ 2183.027501] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 01:21:25 (1769754085) [ 2183.864174] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2183.931377] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.011840] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.141353] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.196433] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.331882] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.465351] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.688363] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.716705] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.900855] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2184.948718] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.064916] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.292240] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.383562] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.458899] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.521224] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.667100] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.786432] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.835217] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.935874] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2185.979221] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.026941] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.140512] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.183948] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.293962] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.381671] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.529103] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.611523] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2186.749536] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.049096] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.149433] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.281770] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.446899] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.575930] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.671308] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2187.918687] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.000701] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.229158] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.413277] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.686560] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.812360] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2188.895528] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2189.126506] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2189.486411] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2189.674802] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2189.727302] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2189.821687] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.022367] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.163096] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.335457] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.549219] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.656109] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2190.955407] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2191.164255] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2191.224059] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2191.446559] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2191.700499] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2191.827517] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.060359] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.142428] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.362087] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.408816] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.462806] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.574725] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.775186] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2192.910854] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.076523] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.188228] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.276347] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.460265] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.609682] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2193.764609] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2194.214836] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2194.353690] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2194.591066] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2194.706162] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2194.969670] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.140303] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.280927] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.400913] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.527732] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.698788] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.800645] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.838865] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.869817] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2195.907784] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.222668] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.443363] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.657654] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.716039] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.762557] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.925836] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2196.999884] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.141046] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.331305] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.416584] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.592846] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.657100] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.808699] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2197.992725] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2198.226628] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2198.398404] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2198.723627] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.007990] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.089901] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.203395] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.292711] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.375413] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.510956] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.590116] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.660403] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.845635] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2199.920520] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.069314] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.218253] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.419087] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.520503] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.660156] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.765570] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2200.906977] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.149896] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.230507] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.311977] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.433621] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.811376] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.890883] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2201.943446] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.089775] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.395027] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.447059] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.506562] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.593033] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 22s idle [ 2202.601052] Lustre: Skipped 1 previous similar message [ 2202.704341] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.806643] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.928655] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2202.994602] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.116212] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.201536] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.312903] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.449940] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.505267] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2203.624952] rw_seq_cst_vs_d (32965): drop_caches: 3 [ 2211.237343] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 01:21:54 (1769754114) [ 2219.543561] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 01:22:02 (1769754122) [ 2227.559512] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 01:22:10 (1769754130) [ 2261.465741] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 01:22:44 (1769754164) [ 2261.882377] bash (35443): drop_caches: 3 [ 2265.023146] bash (35443): drop_caches: 3 [ 2268.171851] bash (35443): drop_caches: 3 [ 2271.320155] bash (35443): drop_caches: 3 [ 2274.514231] bash (35443): drop_caches: 3 [ 2277.621862] bash (35443): drop_caches: 3 [ 2280.734461] bash (35443): drop_caches: 3 [ 2283.819079] bash (35443): drop_caches: 3 [ 2287.057306] bash (35443): drop_caches: 3 [ 2289.635657] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 25s idle [ 2289.639429] Lustre: Skipped 1 previous similar message [ 2290.425969] bash (35443): drop_caches: 3 [ 2293.647692] bash (35443): drop_caches: 3 [ 2296.746716] bash (35443): drop_caches: 3 [ 2299.876928] bash (35443): drop_caches: 3 [ 2303.102839] bash (35443): drop_caches: 3 [ 2308.412696] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 01:23:30 (1769754210) [ 2318.388590] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 01:23:41 (1769754221) [ 2429.981585] LustreError: lustre-OST0000-osc-ffff8c8b48479800: operation ost_write to node 192.168.201.147@tcp failed: rc = -107 [ 2429.992187] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: Connection to lustre-OST0001 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2429.993144] LustreError: Skipped 1 previous similar message [ 2430.005213] LustreError: lustre-OST0000-osc-ffff8c8b48479800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2430.015074] Lustre: Skipped 1 previous similar message [ 2430.019576] Lustre: 2416:0:(llite_lib.c:4195:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.147@tcp:/lustre/fid: [0x200000402:0x6f:0x0]// may get corrupted (rc -5) [ 2430.036692] LustreError: lustre-OST0001-osc-ffff8c8b48bc2000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2430.043264] Lustre: lustre-OST0000-osc-ffff8c8b48479800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2430.065044] Lustre: 2415:0:(llite_lib.c:4195:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.147@tcp:/lustre/fid: [0x200000402:0x70:0x0]// may get corrupted (rc -5) [ 2443.719617] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 01:25:47 (1769754347) [ 2452.710907] Lustre: DEBUG MARKER: loop 5 [ 2458.376787] Lustre: DEBUG MARKER: loop 10 [ 2458.593312] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 24s idle [ 2458.598825] Lustre: Skipped 1 previous similar message [ 2464.110165] Lustre: DEBUG MARKER: loop 15 [ 2469.525423] Lustre: DEBUG MARKER: loop 20 [ 2479.369808] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 01:26:22 (1769754382) [ 2485.810963] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 01:26:28 (1769754388) [ 2493.369450] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 01:26:36 (1769754396) [ 2499.552304] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: disconnect after 22s idle [ 2499.572517] Lustre: Skipped 1 previous similar message [ 2564.580372] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 01:27:47 (1769754467) [ 2573.683108] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 01:27:56 (1769754476) [ 2580.232868] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 01:28:03 (1769754483) [ 2587.893217] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 01:28:10 (1769754490) [ 2595.668740] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 01:28:18 (1769754498) [ 2603.501329] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 01:28:26 (1769754506) [ 2612.796584] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 01:28:36 (1769754516) [ 2621.067883] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2622.957279] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 01:28:45 (1769754525) [ 2632.733127] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 01:28:55 (1769754535) [ 2633.485620] Lustre: *** cfs_fail_loc=314, val=0*** [ 2634.529371] Lustre: *** cfs_fail_loc=314, val=0*** [ 2634.533466] Lustre: Skipped 2 previous similar messages [ 2637.794293] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 24s idle [ 2637.801567] Lustre: Skipped 3 previous similar messages [ 2641.961199] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 01:29:05 (1769754545) [ 2652.338787] Lustre: *** cfs_fail_loc=314, val=0*** [ 2653.168901] Lustre: lustre-OST0000-osc-ffff8c8b48479800: Connection to lustre-OST0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2653.213260] LustreError: lustre-OST0000-osc-ffff8c8b48479800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2653.233832] LustreError: 46393:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8c8b48479800: namespace resource [0x280000401:0x37:0x0].0x0 (ffff8c8b42fadc00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2653.259747] Lustre: lustre-OST0000-osc-ffff8c8b48479800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2653.265923] Lustre: Skipped 1 previous similar message [ 2660.148754] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 01:29:22 (1769754562) [ 2660.874715] LustreError: 46974:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2663.905419] LustreError: 46974:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2669.491877] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 01:29:32 (1769754572) [ 2677.921400] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 01:29:40 (1769754580) [ 2686.676475] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2688.336335] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 01:29:51 (1769754591) [ 2690.153983] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2691.898851] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 01:29:54 (1769754594) [ 2694.124015] Lustre: lustre-MDT0000-mdc-ffff8c8b48bc2000: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2704.362029] LustreError: MGC192.168.201.147@tcp: Connection to MGS (at 192.168.201.147@tcp) was lost; in progress operations using this service will fail [ 2704.400349] Lustre: Evicted from MGS (at 192.168.201.147@tcp) after server handle changed from 0x287141937e3e45e6 to 0x287141937e492c93 [ 2704.423961] Lustre: MGC192.168.201.147@tcp: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2708.714551] Lustre: lustre-MDT0000-mdc-ffff8c8b48479800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2741.428941] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 01:30:44 (1769754644) [ 2791.835632] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 01:31:34 (1769754694) [ 2814.282634] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 01:31:57 (1769754717) [ 2816.992470] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 20s idle [ 2817.011400] Lustre: Skipped 2 previous similar messages [ 2868.209208] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: Connection to lustre-OST0001 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2868.228594] Lustre: Skipped 1 previous similar message [ 2868.248753] LustreError: lustre-OST0001-osc-ffff8c8b48bc2000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2868.273492] LustreError: lustre-OST0001-osc-ffff8c8b48479800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2868.274242] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2868.293625] Lustre: Skipped 1 previous similar message [ 2882.516634] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: Connection to lustre-OST0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2882.543214] Lustre: Skipped 1 previous similar message [ 2882.554131] LustreError: lustre-OST0000-osc-ffff8c8b48bc2000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2882.572131] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 2902.659365] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2903.967678] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid in FULL state after 0 sec [ 2907.644232] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2909.166452] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid in IDLE state after 0 sec [ 2914.280570] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2916.460284] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid in FULL state after 0 sec [ 2920.435828] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2922.770262] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid in IDLE state after 0 sec [ 2937.619328] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2939.889919] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c8b48479800.ost_server_uuid in FULL state after 0 sec [ 2945.490841] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid 50 [ 2947.405730] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8c8b48479800.ost_server_uuid in IDLE state after 0 sec [ 2949.637967] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 01:34:12 (1769754852) [ 2953.209477] Lustre: DEBUG MARKER: Race attempt 0 [ 2956.250357] Lustre: DEBUG MARKER: Wait for 57467 57485 for 60 sec... [ 3025.263540] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 01:35:27 (1769754927) [ 3033.670448] Lustre: DEBUG MARKER: start test - cycle (0) [ 3058.252360] Lustre: DEBUG MARKER: start test - cycle (1) [ 3081.188257] Lustre: DEBUG MARKER: start test - cycle (2) [ 3104.358361] Lustre: DEBUG MARKER: start test - cycle (3) [ 3108.832663] Lustre: lustre-OST0001-osc-ffff8c8b48479800: disconnect after 20s idle [ 3108.849758] Lustre: Skipped 4 previous similar messages [ 3127.868636] Lustre: DEBUG MARKER: start test - cycle (4) [ 3149.992327] Lustre: DEBUG MARKER: start test - cycle (5) [ 3173.964944] Lustre: DEBUG MARKER: start test - cycle (6) [ 3201.254900] Lustre: DEBUG MARKER: start test - cycle (7) [ 3226.925279] Lustre: DEBUG MARKER: start test - cycle (8) [ 3247.606638] Lustre: DEBUG MARKER: start test - cycle (9) [ 3272.916851] Lustre: DEBUG MARKER: start test - cycle (10) [ 3305.026444] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 01:40:08 (1769755208) [ 3378.452375] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 01:41:21 (1769755281) [ 3386.259587] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 01:41:29 (1769755289) [ 3394.484288] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 01:41:37 (1769755297) [ 3402.209298] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 01:41:45 (1769755305) [ 3402.743789] Lustre: *** cfs_fail_loc=411, val=0*** [ 3408.962788] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3411.111063] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 01:41:53 (1769755313) [ 3430.616563] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 01:42:13 (1769755333) [ 3448.315523] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 01:42:31 (1769755351) [ 3469.414598] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 01:42:51 (1769755371) [ 3489.360528] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 01:43:11 (1769755391) [ 3504.635574] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 01:43:27 (1769755407) [ 3521.995275] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 01:43:44 (1769755424) [ 3538.157452] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 01:44:01 (1769755441) [ 3553.143287] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 01:44:16 (1769755456) [ 3568.304551] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 01:44:31 (1769755471) [ 3584.084279] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 01:44:46 (1769755486) [ 3600.493153] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 01:45:03 (1769755503) [ 3617.706094] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 01:45:20 (1769755520) [ 3636.192225] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 22s idle [ 3636.194558] Lustre: Skipped 15 previous similar messages [ 4719.579530] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 02:03:42 (1769756622) [ 4734.596885] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 02:03:57 (1769756637) [ 4749.320530] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 02:04:12 (1769756652) [ 4763.705644] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 02:04:26 (1769756666) [ 4778.110694] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 02:04:40 (1769756680) [ 4783.074201] Lustre: lustre-OST0000-osc-ffff8c8b48bc2000: disconnect after 24s idle [ 4783.081187] Lustre: Skipped 2 previous similar messages [ 4793.167127] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 02:04:55 (1769756695) [ 4808.390734] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 02:05:11 (1769756711) [ 4824.911651] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 02:05:27 (1769756727) [ 4841.496217] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 02:05:44 (1769756744) [ 4955.944602] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 02:07:38 (1769756858) [ 4971.525714] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 02:07:54 (1769756874) [ 4986.618225] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 02:08:09 (1769756889) [ 5002.639438] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 02:08:25 (1769756905) [ 5019.024217] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 02:08:41 (1769756921) [ 5035.394213] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 02:08:58 (1769756938) [ 5051.488309] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 02:09:14 (1769756954) [ 5070.434026] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 02:09:32 (1769756972) [ 5086.416466] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 02:09:49 (1769756989) [ 5235.560538] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 02:12:17 (1769757137) [ 5407.712293] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 20s idle [ 5407.717611] Lustre: Skipped 9 previous similar messages [ 6498.278044] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 02:33:21 (1769758401) [ 6501.344379] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 20s idle [ 6501.348492] Lustre: Skipped 14 previous similar messages [ 6510.478514] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 02:33:33 (1769758413) [ 6522.561976] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 02:33:45 (1769758425) [ 6534.379990] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 02:33:57 (1769758437) [ 6544.867498] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 02:34:08 (1769758448) [ 6557.469798] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 02:34:20 (1769758460) [ 6568.386862] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 02:34:31 (1769758471) [ 6581.161658] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 02:34:44 (1769758484) [ 6594.477115] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 02:34:57 (1769758497) [ 6605.401657] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 02:35:08 (1769758508) [ 6740.347390] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 02:37:23 (1769758643) [ 6752.254262] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 02:37:35 (1769758655) [ 6763.128488] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 02:37:46 (1769758666) [ 6774.106914] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 02:37:57 (1769758677) [ 6784.243918] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 02:38:07 (1769758687) [ 6795.426204] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 02:38:18 (1769758698) [ 6806.918348] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 02:38:30 (1769758710) [ 6817.776224] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 02:38:41 (1769758721) [ 6829.363517] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 02:38:52 (1769758732) [ 7678.211661] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 02:53:01 (1769759581) [ 7687.224959] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 02:53:10 (1769759590) [ 7694.305657] Lustre: lustre-OST0000-osc-ffff8c8b48479800: disconnect after 20s idle [ 7694.308317] Lustre: Skipped 5 previous similar messages [ 7695.774703] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 02:53:19 (1769759599) [ 7705.200421] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 02:53:28 (1769759608) [ 7714.521516] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 02:53:38 (1769759618) [ 7723.367694] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 02:53:46 (1769759626) [ 7732.705489] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 02:53:56 (1769759636) [ 7742.420306] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 02:54:06 (1769759646) [ 7751.555501] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 02:54:15 (1769759655) [ 7760.842084] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 02:54:24 (1769759664) [ 7769.995943] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 02:54:33 (1769759673) [ 7779.109130] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 02:54:42 (1769759682) [ 7786.971443] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 02:54:50 (1769759690) [ 7795.149900] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 02:54:58 (1769759698) [ 7803.722742] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 02:55:07 (1769759707) [ 7812.063123] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 02:55:15 (1769759715) [ 7819.956522] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 02:55:23 (1769759723) [ 7820.114627] LustreError: 6170:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7822.200105] LustreError: 6170:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7828.082068] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 02:55:31 (1769759731) [ 7833.483914] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 02:55:37 (1769759737) [ 7833.682995] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7837.752181] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7837.769698] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7841.832144] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7841.869720] LustreError: 237968:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7845.928100] LustreError: 237968:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7849.390132] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 02:55:53 (1769759753) [ 7857.463352] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 02:56:01 (1769759761) [ 7861.574749] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 02:56:05 (1769759765) [ 7867.153343] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 02:56:10 (1769759770) [ 7893.705187] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 02:56:37 (1769759797) [ 7902.687823] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 02:56:46 (1769759806) [ 7911.133081] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 02:56:54 (1769759814) [ 7925.289957] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 02:57:09 (1769759829) [ 7935.904262] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 02:57:19 (1769759839) [ 7950.374341] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 02:57:34 (1769759854) [ 7965.083426] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 02:57:48 (1769759868) [ 7981.677326] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 02:58:05 (1769759885) [ 7991.999731] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 02:58:15 (1769759895) [ 8031.440873] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 02:58:55 (1769759935) [ 8035.416300] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 8039.795768] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 02:59:03 (1769759943) [ 8043.823460] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 02:59:07 (1769759947) [ 8047.751285] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 02:59:11 (1769759951) [ 8051.507756] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 02:59:15 (1769759955) [ 8075.405717] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 02:59:39 (1769759979) [ 8102.250470] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 03:00:06 (1769760006) [ 8105.559290] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 03:00:09 (1769760009) [ 8108.978722] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 03:00:12 (1769760012) [ 8112.106178] LustreError: lustre-MDT0000-mdc-ffff8c8b48bc2000: operation ldlm_enqueue to node 192.168.201.147@tcp failed: rc = -35 [ 8116.204620] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 03:00:20 (1769760020) [ 8116.542299] LustreError: 2417:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8118.624188] LustreError: 2417:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8124.510102] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 03:00:28 (1769760028) [ 8206.598690] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 03:01:50 (1769760110) [ 8210.857278] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 03:01:54 (1769760114) [ 8217.075195] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 03:02:00 (1769760120) [ 8223.640445] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 03:02:07 (1769760127) [ 8230.138424] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 03:02:13 (1769760133) [ 8239.851938] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 03:02:23 (1769760143) [ 8249.687613] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 03:02:33 (1769760153) [ 8254.305304] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 03:02:38 (1769760158) [ 8259.393569] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 03:02:43 (1769760163) [ 8268.221426] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 03:02:52 (1769760172) [ 8298.464300] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 23s idle [ 8298.467096] Lustre: Skipped 10 previous similar messages [ 8313.245304] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 03:03:37 (1769760217) [ 8431.703772] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 03:05:35 (1769760335) [ 8554.641657] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 03:07:38 (1769760458) [ 8836.511622] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 03:12:20 (1769760740) [ 8866.973762] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 03:12:50 (1769760770) [ 8897.979469] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 03:13:21 (1769760801) [ 8923.105142] Lustre: lustre-OST0001-osc-ffff8c8b48bc2000: disconnect after 24s idle [ 8923.109894] Lustre: Skipped 12 previous similar messages [ 8923.615240] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 03:13:47 (1769760827) [ 8985.970732] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 03:14:49 (1769760889) [ 9037.627375] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 03:15:41 (1769760941) [ 9127.855540] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 03:17:11 (1769761031) [ 9128.892090] LustreError: 285089:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b48bc2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9128.897398] LustreError: 285089:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9128.916072] Lustre: Unmounted lustre-client [ 9129.585178] LustreError: 285102:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b48479800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9129.589543] LustreError: 285102:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9129.592968] LustreError: 285102:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9129.595459] LustreError: 285102:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9129.618980] Lustre: Unmounted lustre-client [ 9187.165190] Lustre: Mounted lustre-client [ 9188.687792] Lustre: Mounted lustre-client [ 9189.745389] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9254.752564] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 03:19:18 (1769761158) [ 9262.757499] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 03:19:26 (1769761166) [ 9266.408855] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 03:19:30 (1769761170) [ 9316.999721] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 03:20:21 (1769761221) [ 9360.630357] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 03:21:04 (1769761264) [ 9364.239657] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 03:21:08 (1769761268) [ 9400.669893] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 03:21:44 (1769761304) [ 9412.819327] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 03:21:56 (1769761316) [ 9453.565737] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 03:22:37 (1769761357) [ 9456.514074] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 03:22:40 (1769761360) [ 9469.142898] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 03:22:53 (1769761373) [ 9472.878641] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 03:22:56 (1769761376) [ 9473.269117] LustreError: 306421:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x23:0x0]: rc = -5 [ 9473.272842] LustreError: 306421:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9473.771501] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x41c:0x0]: rc = -5 [ 9473.775666] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 6 previous similar messages [ 9473.778971] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9473.782220] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) Skipped 6 previous similar messages [ 9474.823289] LustreError: 306539:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x437:0x0]: rc = -5 [ 9474.826212] LustreError: 306539:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 14 previous similar messages [ 9474.828529] LustreError: 306539:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9474.831074] LustreError: 306539:0:(llite_lib.c:3750:ll_prep_inode()) Skipped 14 previous similar messages [ 9476.826839] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x5f:0x0]: rc = -5 [ 9476.831984] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 26 previous similar messages [ 9476.834325] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9476.836145] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) Skipped 26 previous similar messages [ 9480.828854] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xdc:0x0]: rc = -5 [ 9480.832947] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 63 previous similar messages [ 9480.835921] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9480.839100] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) Skipped 63 previous similar messages [ 9585.286890] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x507:0x0]: rc = -5 [ 9585.289822] LustreError: 306260:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 51 previous similar messages [ 9585.292126] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9585.294047] LustreError: 306260:0:(llite_lib.c:3750:ll_prep_inode()) Skipped 51 previous similar messages [ 9585.298692] LustreError: lustre-MDT0000-mdc-ffff8c8b7785a800: operation mds_getattr_lock to node 192.168.201.147@tcp failed: rc = -107 [ 9585.301250] Lustre: lustre-MDT0000-mdc-ffff8c8b7785a800: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9585.306086] LustreError: lustre-MDT0000-mdc-ffff8c8b7785a800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9585.310207] LustreError: 307286:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8c8b7785a800: inode [0x2000013a1:0x408:0x0] mdc close failed: rc = -108 [ 9585.310372] LustreError: 307265:0:(file.c:6147:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 9585.319857] Lustre: lustre-MDT0000-mdc-ffff8c8b7785a800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [ 9587.470924] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 03:24:51 (1769761491) [ 9589.753746] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 03:24:53 (1769761493) [ 9641.084242] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 03:25:45 (1769761545) [ 9641.563130] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9642.133512] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 03:25:46 (1769761546) [ 9684.748814] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 03:26:28 (1769761588) [ 9686.987268] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 03:26:31 (1769761591) [ 9809.178075] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 03:28:33 (1769761713) [ 9816.554889] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 03:28:40 (1769761720) [ 9818.977920] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 03:28:43 (1769761723) [10001.112263] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 03:31:45 (1769761905) [10105.312133] Lustre: lustre-OST0000-osc-ffff8c8b435ea000: disconnect after 21s idle [10105.314081] Lustre: Skipped 9 previous similar messages [10183.102864] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 03:34:47 (1769762087) [10185.066567] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 03:34:49 (1769762089) [10193.678736] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 03:34:57 (1769762097) [10193.728576] Lustre: DEBUG MARKER: write [10193.744279] LustreError: 306648:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10195.749411] Lustre: DEBUG MARKER: kill 370879 [10195.751188] LustreError: 370879:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10198.840056] LustreError: 306648:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10201.784099] LustreError: 370879:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10203.687442] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 03:35:07 (1769762107) [10205.831290] LustreError: 371492:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10215.920072] LustreError: 371492:0:(rw.c:1959:ll_readpage()) cfs_fail_timeout id 1422 awake [10217.912932] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 03:35:21 (1769762121) [10217.997580] LustreError: 372079:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10220.080106] LustreError: 372079:0:(rw.c:2204:ll_readpage()) cfs_fail_timeout interrupted [10225.967734] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 03:35:30 (1769762130) [10226.423286] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10226.932891] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 03:35:30 (1769762130) [10229.220639] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 03:35:33 (1769762133) [10231.258250] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 03:35:35 (1769762135) [10233.256875] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 03:35:37 (1769762137) [10235.485928] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 03:35:39 (1769762139) [10237.582922] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 03:35:41 (1769762141) [10239.622822] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 03:35:43 (1769762143) [10242.738847] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 03:35:46 (1769762146) [10245.147435] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 03:35:49 (1769762149) [10245.741976] Lustre: *** cfs_fail_loc=415, val=0*** [10252.070071] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 03:35:56 (1769762156) [10271.064431] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 03:36:15 (1769762175) [10271.165825] LustreError: 287348:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [10271.170294] LustreError: 287348:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [10276.168068] LustreError: 287348:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10286.376077] LustreError: 287348:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10286.378051] LustreError: 287348:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [10293.696793] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 03:36:37 (1769762197) [10295.775554] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 03:36:39 (1769762199) [10297.813256] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 03:36:41 (1769762201) [10299.867722] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 03:36:43 (1769762203) [10304.356739] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 03:36:48 (1769762208) [10312.587626] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 03:36:56 (1769762216) [10312.776857] LustreError: 382810:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [10312.779915] LustreError: 382810:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [10316.840228] LustreError: 382810:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [10316.841992] LustreError: 382810:0:(osc_request.c:2944:osc_build_rpc()) Skipped 3 previous similar messages [10318.915104] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 03:37:02 (1769762222) [10320.109560] LustreError: 383519:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b7785a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10320.112340] LustreError: 383519:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10320.116217] LustreError: 383519:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10320.118821] LustreError: 383519:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10320.139095] Lustre: Unmounted lustre-client [10320.618806] LustreError: 383539:0:(obd_class.h:479:obd_check_dev()) Device 0 not setup [10320.621137] LustreError: 383539:0:(obd_class.h:479:obd_check_dev()) Skipped 15 previous similar messages [10321.104831] Lustre: DEBUG MARKER: Iteration 1 [10321.197030] LustreError: 383702:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10321.197130] LustreError: 383703:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10321.201373] LustreError: 383702:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10321.248430] Lustre: Mounted lustre-client [10321.679962] LustreError: 383815:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b48478000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10321.683452] LustreError: 383816:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10321.683908] LustreError: 383815:0:(lov_obd.c:783:lov_cleanup()) Skipped 5 previous similar messages [10321.686079] LustreError: 383816:0:(obd_class.h:479:obd_check_dev()) Skipped 1 previous similar message [10321.726281] Lustre: Unmounted lustre-client [10321.727287] Lustre: Skipped 1 previous similar message [10322.605910] Key type lgssc unregistered [10322.721541] LNet: 384059:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10322.724318] LNetError: 384059:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10322.734760] LNet: Removed LNI 192.168.201.47@tcp [10323.019099] Key type .llcrypt unregistered [10323.020055] Key type ._llcrypt unregistered [10323.388328] Key type ._llcrypt registered [10323.389793] Key type .llcrypt registered [10323.529887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10323.534856] alg: No test for adler32 (adler32-zlib) [10324.482525] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10324.729502] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10326.328159] Key type lgssc registered [10326.828073] Lustre: Echo OBD driver; http://www.lustre.org/ [10330.713722] Lustre: DEBUG MARKER: Iteration 2 [10330.817296] LustreError: 384888:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10330.817330] LustreError: 384889:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10330.821629] LustreError: 384888:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [10331.869212] Lustre: Mounted lustre-client [10331.869980] Lustre: Skipped 1 previous similar message [10332.281558] LustreError: 384999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44344800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10332.283992] LustreError: 384998:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10332.285855] LustreError: 384999:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10332.290641] LustreError: 384998:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10332.314149] Lustre: Unmounted lustre-client [10333.202736] Key type lgssc unregistered [10333.314465] LNet: 385241:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10333.316926] LNetError: 385241:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10333.327667] LNet: Removed LNI 192.168.201.47@tcp [10333.542082] Key type .llcrypt unregistered [10333.543118] Key type ._llcrypt unregistered [10333.751334] Key type ._llcrypt registered [10333.752966] Key type .llcrypt registered [10333.966101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10333.971862] alg: No test for adler32 (adler32-zlib) [10334.823585] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10334.904374] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10336.488106] Key type lgssc registered [10336.849802] Lustre: Echo OBD driver; http://www.lustre.org/ [10340.306960] Lustre: DEBUG MARKER: Iteration 3 [10340.448735] LustreError: 386070:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10340.449152] LustreError: 386071:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10340.452926] LustreError: 386070:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [10341.512205] Lustre: Mounted lustre-client [10342.011406] LustreError: 386184:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b4aef7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10342.015708] LustreError: 386184:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10342.015871] LustreError: 386183:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10342.019970] LustreError: 386183:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10342.058160] Lustre: Unmounted lustre-client [10342.996362] Key type lgssc unregistered [10343.118327] LNet: 386426:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10343.120271] LNetError: 386426:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10343.130592] LNet: Removed LNI 192.168.201.47@tcp [10343.346080] Key type .llcrypt unregistered [10343.347112] Key type ._llcrypt unregistered [10343.601417] Key type ._llcrypt registered [10343.602388] Key type .llcrypt registered [10343.784697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10343.789127] alg: No test for adler32 (adler32-zlib) [10344.647569] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10344.729280] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10346.312159] Key type lgssc registered [10346.672157] Lustre: Echo OBD driver; http://www.lustre.org/ [10350.045190] Lustre: DEBUG MARKER: Iteration 4 [10350.134357] LustreError: 387254:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10350.134399] LustreError: 387255:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10350.139147] LustreError: 387254:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10351.192709] Lustre: Mounted lustre-client [10351.194774] Lustre: Skipped 1 previous similar message [10351.601405] LustreError: 387371:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b4aef7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10351.604791] LustreError: 387372:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10351.606612] LustreError: 387371:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10351.643268] Lustre: Unmounted lustre-client [10351.644328] Lustre: Skipped 1 previous similar message [10352.524244] Key type lgssc unregistered [10352.635470] LNet: 387614:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10352.637453] LNetError: 387614:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10352.647614] LNet: Removed LNI 192.168.201.47@tcp [10352.847077] Key type .llcrypt unregistered [10352.848056] Key type ._llcrypt unregistered [10353.078739] Key type ._llcrypt registered [10353.079545] Key type .llcrypt registered [10353.248749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10353.264034] alg: No test for adler32 (adler32-zlib) [10354.114629] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10354.197223] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10355.776107] Key type lgssc registered [10356.126868] Lustre: Echo OBD driver; http://www.lustre.org/ [10359.331968] Lustre: DEBUG MARKER: Iteration 5 [10359.422933] LustreError: 388441:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10359.422975] LustreError: 388442:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10359.427516] LustreError: 388441:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10360.479321] Lustre: Mounted lustre-client [10360.480479] Lustre: Skipped 1 previous similar message [10361.049308] LustreError: 388548:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43cbe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10361.053446] LustreError: 388548:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10361.053634] LustreError: 388549:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10361.121149] Lustre: Unmounted lustre-client [10362.150496] Key type lgssc unregistered [10362.268711] LNet: 388792:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10362.271286] LNetError: 388792:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10362.278331] LNet: Removed LNI 192.168.201.47@tcp [10362.518125] Key type .llcrypt unregistered [10362.519941] Key type ._llcrypt unregistered [10362.786974] Key type ._llcrypt registered [10362.788042] Key type .llcrypt registered [10362.994028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10362.998852] alg: No test for adler32 (adler32-zlib) [10363.856609] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10363.944435] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10365.536170] Key type lgssc registered [10365.961990] Lustre: Echo OBD driver; http://www.lustre.org/ [10369.540817] Lustre: DEBUG MARKER: Iteration 6 [10369.630877] LustreError: 389621:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10369.630894] LustreError: 389623:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10369.636190] LustreError: 389621:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10370.684390] Lustre: Mounted lustre-client [10371.084035] LustreError: 389736:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42e85000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10371.087728] LustreError: 389736:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10371.091446] LustreError: 389735:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10371.094501] LustreError: 389735:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10371.143048] Lustre: Unmounted lustre-client [10372.057691] Key type lgssc unregistered [10372.164436] LNet: 389977:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10372.166178] LNetError: 389977:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10372.174591] LNet: Removed LNI 192.168.201.47@tcp [10372.387113] Key type .llcrypt unregistered [10372.387957] Key type ._llcrypt unregistered [10372.630719] Key type ._llcrypt registered [10372.633332] Key type .llcrypt registered [10372.805932] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10372.811435] alg: No test for adler32 (adler32-zlib) [10373.655663] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10373.726774] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10375.304112] Key type lgssc registered [10375.664971] Lustre: Echo OBD driver; http://www.lustre.org/ [10378.883347] Lustre: DEBUG MARKER: Iteration 7 [10378.975225] LustreError: 390806:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10378.976140] LustreError: 390807:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10378.980928] LustreError: 390806:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10380.034287] Lustre: Mounted lustre-client [10380.035516] Lustre: Skipped 1 previous similar message [10380.458124] LustreError: 390920:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43cbd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10380.461522] LustreError: 390919:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10380.463015] LustreError: 390920:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10380.491048] Lustre: Unmounted lustre-client [10381.364889] Key type lgssc unregistered [10381.483485] LNet: 391162:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10381.485462] LNetError: 391162:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10381.494584] LNet: Removed LNI 192.168.201.47@tcp [10381.723101] Key type .llcrypt unregistered [10381.723963] Key type ._llcrypt unregistered [10381.964256] Key type ._llcrypt registered [10381.966650] Key type .llcrypt registered [10382.143301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10382.147996] alg: No test for adler32 (adler32-zlib) [10383.009833] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10383.085261] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10384.664141] Key type lgssc registered [10385.026711] Lustre: Echo OBD driver; http://www.lustre.org/ [10388.414299] Lustre: DEBUG MARKER: Iteration 8 [10388.524774] LustreError: 391990:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10388.524840] LustreError: 391991:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10388.529293] LustreError: 391990:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10389.601752] Lustre: Mounted lustre-client [10389.602797] Lustre: Skipped 1 previous similar message [10390.050607] LustreError: 392103:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5275d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10390.053325] LustreError: 392104:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10390.055151] LustreError: 392103:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10390.113386] Lustre: Unmounted lustre-client [10391.018227] Key type lgssc unregistered [10391.129428] LNet: 392346:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10391.131823] LNetError: 392346:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10391.138947] LNet: Removed LNI 192.168.201.47@tcp [10391.359101] Key type .llcrypt unregistered [10391.360251] Key type ._llcrypt unregistered [10391.572561] Key type ._llcrypt registered [10391.573535] Key type .llcrypt registered [10391.790283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10391.795412] alg: No test for adler32 (adler32-zlib) [10392.650389] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10392.732579] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10394.304119] Key type lgssc registered [10394.654908] Lustre: Echo OBD driver; http://www.lustre.org/ [10397.870251] Lustre: DEBUG MARKER: Iteration 9 [10397.962296] LustreError: 393174:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10397.963074] LustreError: 393175:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10397.967500] LustreError: 393174:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10399.012646] Lustre: Mounted lustre-client [10399.014119] Lustre: Skipped 1 previous similar message [10399.415911] LustreError: 393288:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b74706000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10399.420611] LustreError: 393288:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10399.423142] LustreError: 393288:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10399.455324] Lustre: Unmounted lustre-client [10400.365818] Key type lgssc unregistered [10400.474511] LNet: 393531:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10400.477031] LNetError: 393531:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10400.483590] LNet: Removed LNI 192.168.201.47@tcp [10400.692113] Key type .llcrypt unregistered [10400.693152] Key type ._llcrypt unregistered [10400.941448] Key type ._llcrypt registered [10400.942395] Key type .llcrypt registered [10401.120110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10401.125406] alg: No test for adler32 (adler32-zlib) [10401.981283] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10402.062080] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10403.648114] Key type lgssc registered [10404.011430] Lustre: Echo OBD driver; http://www.lustre.org/ [10407.119481] Lustre: DEBUG MARKER: Iteration 10 [10407.208151] LustreError: 394360:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10407.208381] LustreError: 394359:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10407.211652] LustreError: 394360:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10408.259395] Lustre: Mounted lustre-client [10408.260496] Lustre: Skipped 1 previous similar message [10408.670494] LustreError: 394473:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42fe2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10408.672632] LustreError: 394472:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10408.673646] LustreError: 394473:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10408.716685] Lustre: Unmounted lustre-client [10408.717634] Lustre: Skipped 1 previous similar message [10409.602688] Key type lgssc unregistered [10409.706469] LNet: 394716:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10409.708892] LNetError: 394716:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10409.717669] LNet: Removed LNI 192.168.201.47@tcp [10409.939090] Key type .llcrypt unregistered [10409.939971] Key type ._llcrypt unregistered [10410.294981] Key type ._llcrypt registered [10410.299881] Key type .llcrypt registered [10410.388665] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10410.393241] alg: No test for adler32 (adler32-zlib) [10411.245520] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10411.327766] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10412.904150] Key type lgssc registered [10413.257955] Lustre: Echo OBD driver; http://www.lustre.org/ [10416.459499] Lustre: DEBUG MARKER: Iteration 11 [10416.548527] LustreError: 395544:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10416.548569] LustreError: 395545:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10416.552217] LustreError: 395544:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10417.607087] Lustre: Mounted lustre-client [10418.023235] LustreError: 395652:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b47196000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10418.028281] LustreError: 395652:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10418.032379] LustreError: 395653:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10418.102701] Lustre: Unmounted lustre-client [10418.998898] Key type lgssc unregistered [10419.117484] LNet: 395895:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10419.119314] LNetError: 395895:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10419.129655] LNet: Removed LNI 192.168.201.47@tcp [10419.353141] Key type .llcrypt unregistered [10419.354906] Key type ._llcrypt unregistered [10419.582258] Key type ._llcrypt registered [10419.584051] Key type .llcrypt registered [10419.766893] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10419.772465] alg: No test for adler32 (adler32-zlib) [10420.619800] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10420.696149] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10422.280140] Key type lgssc registered [10422.644030] Lustre: Echo OBD driver; http://www.lustre.org/ [10426.193281] Lustre: DEBUG MARKER: Iteration 12 [10426.293514] LustreError: 396723:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10426.293566] LustreError: 396724:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10426.298013] LustreError: 396723:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [10427.349541] Lustre: Mounted lustre-client [10427.785143] LustreError: 396837:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43e8c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10427.788291] LustreError: 396837:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10427.790720] LustreError: 396836:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10427.793462] LustreError: 396836:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10427.822563] Lustre: Unmounted lustre-client [10428.711413] Key type lgssc unregistered [10428.825444] LNet: 397079:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10428.827782] LNetError: 397079:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10428.837596] LNet: Removed LNI 192.168.201.47@tcp [10429.060116] Key type .llcrypt unregistered [10429.061812] Key type ._llcrypt unregistered [10429.324920] Key type ._llcrypt registered [10429.326116] Key type .llcrypt registered [10429.493952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10429.499589] alg: No test for adler32 (adler32-zlib) [10430.358479] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10430.448949] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10432.032176] Key type lgssc registered [10432.438360] Lustre: Echo OBD driver; http://www.lustre.org/ [10435.800356] Lustre: DEBUG MARKER: Iteration 13 [10435.901545] LustreError: 397909:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10435.902671] LustreError: 397908:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10435.907832] LustreError: 397909:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10436.959932] Lustre: Mounted lustre-client [10437.494890] LustreError: 398016:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b7afb3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10437.501888] LustreError: 398016:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10437.504073] LustreError: 398016:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10437.542706] Lustre: Unmounted lustre-client [10438.547978] Key type lgssc unregistered [10438.659398] LNet: 398257:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10438.661611] LNetError: 398257:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10438.668720] LNet: Removed LNI 192.168.201.47@tcp [10438.926263] Key type .llcrypt unregistered [10438.927334] Key type ._llcrypt unregistered [10439.160415] Key type ._llcrypt registered [10439.161727] Key type .llcrypt registered [10439.359172] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10439.365200] alg: No test for adler32 (adler32-zlib) [10440.219202] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10440.293612] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10441.872146] Key type lgssc registered [10442.244296] Lustre: Echo OBD driver; http://www.lustre.org/ [10445.732893] Lustre: DEBUG MARKER: Iteration 14 [10445.829485] LustreError: 399086:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10445.829533] LustreError: 399087:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10445.833714] LustreError: 399086:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10446.904102] Lustre: Mounted lustre-client [10446.905314] Lustre: Skipped 1 previous similar message [10447.326879] LustreError: 399202:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44b99800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10447.330950] LustreError: 399202:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10447.331279] LustreError: 399201:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10447.336415] LustreError: 399201:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10447.371066] Lustre: Unmounted lustre-client [10448.340212] Key type lgssc unregistered [10448.458466] LNet: 399443:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10448.461582] LNetError: 399443:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10448.468772] LNet: Removed LNI 192.168.201.47@tcp [10448.733121] Key type .llcrypt unregistered [10448.734984] Key type ._llcrypt unregistered [10448.960367] Key type ._llcrypt registered [10448.961524] Key type .llcrypt registered [10449.164723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10449.172176] alg: No test for adler32 (adler32-zlib) [10450.030291] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10450.116137] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10451.704146] Key type lgssc registered [10452.099635] Lustre: Echo OBD driver; http://www.lustre.org/ [10455.670336] Lustre: DEBUG MARKER: Iteration 15 [10455.763225] LustreError: 400271:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10455.765814] LustreError: 400272:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10455.768800] LustreError: 400271:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10456.833504] Lustre: Mounted lustre-client [10456.834354] Lustre: Skipped 1 previous similar message [10457.282901] LustreError: 400377:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42b1d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10457.286672] LustreError: 400377:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10457.287925] LustreError: 400378:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10457.291496] LustreError: 400378:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10457.335898] Lustre: Unmounted lustre-client [10458.288044] Key type lgssc unregistered [10458.422435] LNet: 400619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10458.425151] LNetError: 400619:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10458.433663] LNet: Removed LNI 192.168.201.47@tcp [10458.663113] Key type .llcrypt unregistered [10458.664384] Key type ._llcrypt unregistered [10458.992176] Key type ._llcrypt registered [10458.993572] Key type .llcrypt registered [10459.104764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10459.110150] alg: No test for adler32 (adler32-zlib) [10459.973967] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10460.056284] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10461.640154] Key type lgssc registered [10462.005948] Lustre: Echo OBD driver; http://www.lustre.org/ [10465.326871] Lustre: DEBUG MARKER: Iteration 16 [10465.430172] LustreError: 401448:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10465.430490] LustreError: 401449:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10465.435054] LustreError: 401448:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10466.483440] Lustre: Mounted lustre-client [10466.891503] LustreError: 401561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b604a1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10466.893894] LustreError: 401562:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [10466.895309] LustreError: 401561:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10466.938064] Lustre: Unmounted lustre-client [10467.810803] Key type lgssc unregistered [10467.925553] LNet: 401804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10467.928602] LNetError: 401804:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10467.942693] LNet: Removed LNI 192.168.201.47@tcp [10468.178098] Key type .llcrypt unregistered [10468.179431] Key type ._llcrypt unregistered [10468.428201] Key type ._llcrypt registered [10468.429612] Key type .llcrypt registered [10468.639072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10468.646622] alg: No test for adler32 (adler32-zlib) [10469.497696] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10469.573394] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10471.152151] Key type lgssc registered [10471.514586] Lustre: Echo OBD driver; http://www.lustre.org/ [10474.723965] Lustre: DEBUG MARKER: Iteration 17 [10474.820920] LustreError: 402632:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10474.822552] LustreError: 402633:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10474.826183] LustreError: 402632:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10475.879473] Lustre: Mounted lustre-client [10476.284485] LustreError: 402747:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b81cc9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10476.286511] LustreError: 402746:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10476.287805] LustreError: 402747:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10476.336132] Lustre: Unmounted lustre-client [10476.337331] Lustre: Skipped 1 previous similar message [10477.299450] Key type lgssc unregistered [10477.421392] LNet: 402989:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10477.424639] LNetError: 402989:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10477.431705] LNet: Removed LNI 192.168.201.47@tcp [10477.667113] Key type .llcrypt unregistered [10477.668187] Key type ._llcrypt unregistered [10477.975744] Key type ._llcrypt registered [10477.983138] Key type .llcrypt registered [10478.228427] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10478.233172] alg: No test for adler32 (adler32-zlib) [10479.087992] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10479.175435] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10480.752143] Key type lgssc registered [10481.094555] Lustre: Echo OBD driver; http://www.lustre.org/ [10484.534339] Lustre: DEBUG MARKER: Iteration 18 [10484.640297] LustreError: 403818:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10484.641058] LustreError: 403817:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10484.644900] LustreError: 403818:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10485.700292] Lustre: Mounted lustre-client [10485.701997] Lustre: Skipped 1 previous similar message [10486.256651] LustreError: 403932:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b60bb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10486.259195] LustreError: 403933:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10486.260968] LustreError: 403932:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10486.308718] Lustre: Unmounted lustre-client [10487.259833] Key type lgssc unregistered [10487.365587] LNet: 404175:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10487.368251] LNetError: 404175:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10487.374676] LNet: Removed LNI 192.168.201.47@tcp [10487.603107] Key type .llcrypt unregistered [10487.604438] Key type ._llcrypt unregistered [10487.837364] Key type ._llcrypt registered [10487.838428] Key type .llcrypt registered [10488.021572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10488.026342] alg: No test for adler32 (adler32-zlib) [10488.884117] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10488.979296] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10490.560242] Key type lgssc registered [10490.922049] Lustre: Echo OBD driver; http://www.lustre.org/ [10494.239346] Lustre: DEBUG MARKER: Iteration 19 [10494.330379] LustreError: 405005:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10494.330427] LustreError: 405004:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10494.333746] LustreError: 405005:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10495.377366] Lustre: Mounted lustre-client [10495.797311] LustreError: 405118:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b51877000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10495.800585] LustreError: 405118:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10495.800707] LustreError: 405117:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10495.804506] LustreError: 405117:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10495.832735] Lustre: Unmounted lustre-client [10496.719176] Key type lgssc unregistered [10496.833506] LNet: 405360:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10496.836063] LNetError: 405360:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10496.846627] LNet: Removed LNI 192.168.201.47@tcp [10497.064138] Key type .llcrypt unregistered [10497.065620] Key type ._llcrypt unregistered [10497.344601] Key type ._llcrypt registered [10497.346080] Key type .llcrypt registered [10497.524479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10497.528874] alg: No test for adler32 (adler32-zlib) [10498.381334] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10498.459373] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10500.040153] Key type lgssc registered [10500.382091] Lustre: Echo OBD driver; http://www.lustre.org/ [10503.575767] Lustre: DEBUG MARKER: Iteration 20 [10503.690254] LustreError: 406188:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10503.690480] LustreError: 406189:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10503.695832] LustreError: 406188:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10504.746170] Lustre: Mounted lustre-client [10505.153954] LustreError: 406297:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44343800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10505.157952] LustreError: 406298:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [10505.158944] LustreError: 406297:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10505.205104] Lustre: Unmounted lustre-client [10506.114887] Key type lgssc unregistered [10506.222797] LNet: 406539:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10506.224965] LNetError: 406539:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10506.232406] LNet: Removed LNI 192.168.201.47@tcp [10506.439113] Key type .llcrypt unregistered [10506.440395] Key type ._llcrypt unregistered [10506.719392] Key type ._llcrypt registered [10506.720429] Key type .llcrypt registered [10506.881554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10506.886817] alg: No test for adler32 (adler32-zlib) [10507.730146] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10507.804795] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10509.376088] Key type lgssc registered [10509.775118] Lustre: Echo OBD driver; http://www.lustre.org/ [10513.524923] Lustre: DEBUG MARKER: Iteration 21 [10513.652073] LustreError: 407367:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10513.652129] LustreError: 407368:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10513.658391] LustreError: 407367:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10514.729136] Lustre: Mounted lustre-client [10515.211164] LustreError: 407481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5283b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10515.214959] LustreError: 407482:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10515.215992] LustreError: 407481:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10515.258099] Lustre: Unmounted lustre-client [10516.269264] Key type lgssc unregistered [10516.393543] LNet: 407725:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10516.396940] LNetError: 407725:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10516.408703] LNet: Removed LNI 192.168.201.47@tcp [10516.657115] Key type .llcrypt unregistered [10516.658082] Key type ._llcrypt unregistered [10517.020565] Key type ._llcrypt registered [10517.022041] Key type .llcrypt registered [10517.167034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10517.172766] alg: No test for adler32 (adler32-zlib) [10518.029587] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10518.114948] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10519.696150] Key type lgssc registered [10520.123771] Lustre: Echo OBD driver; http://www.lustre.org/ [10523.968712] Lustre: DEBUG MARKER: Iteration 22 [10524.077817] LustreError: 408553:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10524.079636] LustreError: 408554:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10524.085281] LustreError: 408553:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10525.146824] Lustre: Mounted lustre-client [10525.610773] LustreError: 408666:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b45306000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10525.618055] LustreError: 408666:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10525.680811] Lustre: Unmounted lustre-client [10526.714574] Key type lgssc unregistered [10526.845635] LNet: 408910:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10526.848626] LNetError: 408910:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10526.860745] LNet: Removed LNI 192.168.201.47@tcp [10527.119123] Key type .llcrypt unregistered [10527.120896] Key type ._llcrypt unregistered [10527.423348] Key type ._llcrypt registered [10527.425026] Key type .llcrypt registered [10527.606068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10527.611610] alg: No test for adler32 (adler32-zlib) [10528.483157] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10528.590659] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10530.184156] Key type lgssc registered [10530.630245] Lustre: Echo OBD driver; http://www.lustre.org/ [10533.876367] Lustre: DEBUG MARKER: Iteration 23 [10533.966392] LustreError: 409740:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10533.966447] LustreError: 409741:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10533.970358] LustreError: 409740:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10535.021980] Lustre: Mounted lustre-client [10535.422828] LustreError: 409854:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43509800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10535.425794] LustreError: 409854:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10535.428949] LustreError: 409853:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10535.476094] Lustre: Unmounted lustre-client [10536.388527] Key type lgssc unregistered [10536.506586] LNet: 410095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10536.509626] LNetError: 410095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10536.518655] LNet: Removed LNI 192.168.201.47@tcp [10536.743156] Key type .llcrypt unregistered [10536.744409] Key type ._llcrypt unregistered [10536.982255] Key type ._llcrypt registered [10536.983474] Key type .llcrypt registered [10537.163492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10537.168264] alg: No test for adler32 (adler32-zlib) [10538.018497] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10538.089135] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10539.664204] Key type lgssc registered [10540.023563] Lustre: Echo OBD driver; http://www.lustre.org/ [10543.409131] Lustre: DEBUG MARKER: Iteration 24 [10543.501779] LustreError: 410924:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10543.501831] LustreError: 410925:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10543.507955] LustreError: 410924:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10544.558132] Lustre: Mounted lustre-client [10544.559266] Lustre: Skipped 1 previous similar message [10544.960202] LustreError: 411038:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b48b5c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10544.963720] LustreError: 411039:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10544.964178] LustreError: 411038:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10545.009888] Lustre: Unmounted lustre-client [10545.911706] Key type lgssc unregistered [10546.012456] LNet: 411281:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10546.014464] LNetError: 411281:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10546.021597] LNet: Removed LNI 192.168.201.47@tcp [10546.238110] Key type .llcrypt unregistered [10546.239518] Key type ._llcrypt unregistered [10546.467786] Key type ._llcrypt registered [10546.468737] Key type .llcrypt registered [10546.676351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10546.680804] alg: No test for adler32 (adler32-zlib) [10547.538482] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10547.618041] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10549.208149] Key type lgssc registered [10549.577923] Lustre: Echo OBD driver; http://www.lustre.org/ [10552.804028] Lustre: DEBUG MARKER: Iteration 25 [10552.897520] LustreError: 412110:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10552.898063] LustreError: 412111:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10552.902439] LustreError: 412110:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10553.956686] Lustre: Mounted lustre-client [10553.957569] Lustre: Skipped 1 previous similar message [10554.362641] LustreError: 412222:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5890b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10554.364360] LustreError: 412223:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10554.365966] LustreError: 412222:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10554.397444] Lustre: Unmounted lustre-client [10555.331682] Key type lgssc unregistered [10555.438507] LNet: 412466:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10555.441703] LNetError: 412466:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10555.449710] LNet: Removed LNI 192.168.201.47@tcp [10555.679106] Key type .llcrypt unregistered [10555.679913] Key type ._llcrypt unregistered [10555.942567] Key type ._llcrypt registered [10555.950916] Key type .llcrypt registered [10556.112498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10556.117083] alg: No test for adler32 (adler32-zlib) [10556.966123] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10557.039483] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10558.616150] Key type lgssc registered [10559.018388] Lustre: Echo OBD driver; http://www.lustre.org/ [10562.166607] Lustre: DEBUG MARKER: Iteration 26 [10562.257305] LustreError: 413294:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10562.257495] LustreError: 413296:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10562.261151] LustreError: 413294:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10563.322389] Lustre: Mounted lustre-client [10563.323243] Lustre: Skipped 1 previous similar message [10563.724840] LustreError: 413409:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b52e6b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10563.728451] LustreError: 413410:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10563.728629] LustreError: 413409:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10563.781775] Lustre: Unmounted lustre-client [10564.681321] Key type lgssc unregistered [10564.803689] LNet: 413651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10564.805872] LNetError: 413651:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10564.818357] LNet: Removed LNI 192.168.201.47@tcp [10565.050106] Key type .llcrypt unregistered [10565.051420] Key type ._llcrypt unregistered [10565.347250] Key type ._llcrypt registered [10565.348405] Key type .llcrypt registered [10565.552321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10565.557932] alg: No test for adler32 (adler32-zlib) [10566.438158] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10566.531258] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10568.120140] Key type lgssc registered [10568.551796] Lustre: Echo OBD driver; http://www.lustre.org/ [10572.063522] Lustre: DEBUG MARKER: Iteration 27 [10572.177216] LustreError: 414480:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10572.177261] LustreError: 414479:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10572.181761] LustreError: 414480:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10573.236643] Lustre: Mounted lustre-client [10573.237618] Lustre: Skipped 1 previous similar message [10573.634850] LustreError: 414594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43a8f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10573.636857] LustreError: 414593:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10573.637722] LustreError: 414594:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10573.681638] Lustre: Unmounted lustre-client [10573.682608] Lustre: Skipped 1 previous similar message [10574.575789] Key type lgssc unregistered [10574.685427] LNet: 414835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10574.687807] LNetError: 414835:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10574.694703] LNet: Removed LNI 192.168.201.47@tcp [10574.897121] Key type .llcrypt unregistered [10574.898232] Key type ._llcrypt unregistered [10575.108276] Key type ._llcrypt registered [10575.109600] Key type .llcrypt registered [10575.305296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10575.309698] alg: No test for adler32 (adler32-zlib) [10576.147520] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10576.217295] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10577.800133] Key type lgssc registered [10578.210552] Lustre: Echo OBD driver; http://www.lustre.org/ [10581.888664] Lustre: DEBUG MARKER: Iteration 28 [10581.994757] LustreError: 415664:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10581.995086] LustreError: 415665:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10582.000947] LustreError: 415664:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10583.055316] Lustre: Mounted lustre-client [10583.548474] LustreError: 415782:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b777ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10583.551946] LustreError: 415781:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10583.553758] LustreError: 415782:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10583.598112] Lustre: Unmounted lustre-client [10584.553758] Key type lgssc unregistered [10584.665442] LNet: 416024:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10584.668570] LNetError: 416024:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10584.675687] LNet: Removed LNI 192.168.201.47@tcp [10584.904157] Key type .llcrypt unregistered [10584.905784] Key type ._llcrypt unregistered [10585.201066] Key type ._llcrypt registered [10585.218862] Key type .llcrypt registered [10585.394920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10585.401446] alg: No test for adler32 (adler32-zlib) [10586.268121] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10586.359550] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10587.944197] Key type lgssc registered [10588.359392] Lustre: Echo OBD driver; http://www.lustre.org/ [10592.080336] Lustre: DEBUG MARKER: Iteration 29 [10592.186794] LustreError: 416852:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10592.191175] LustreError: 416853:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10592.193979] LustreError: 416852:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10593.255107] Lustre: Mounted lustre-client [10593.256787] Lustre: Skipped 1 previous similar message [10593.735217] LustreError: 416964:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43c46000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10593.741680] LustreError: 416964:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10593.745447] LustreError: 416964:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10593.781544] Lustre: Unmounted lustre-client [10594.798622] Key type lgssc unregistered [10594.914340] LNet: 417205:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10594.917923] LNetError: 417205:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10594.927772] LNet: Removed LNI 192.168.201.47@tcp [10595.185124] Key type .llcrypt unregistered [10595.186862] Key type ._llcrypt unregistered [10595.490995] Key type ._llcrypt registered [10595.492674] Key type .llcrypt registered [10595.668037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10595.673420] alg: No test for adler32 (adler32-zlib) [10596.526459] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10596.604100] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10598.184121] Key type lgssc registered [10598.546528] Lustre: Echo OBD driver; http://www.lustre.org/ [10601.737690] Lustre: DEBUG MARKER: Iteration 30 [10601.831745] LustreError: 418033:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10601.835717] LustreError: 418034:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10601.837303] LustreError: 418033:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10602.882444] Lustre: Mounted lustre-client [10603.292858] LustreError: 418148:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b594af800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10603.297795] LustreError: 418148:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10603.330659] Lustre: Unmounted lustre-client [10604.240418] Key type lgssc unregistered [10604.347594] LNet: 418391:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10604.349737] LNetError: 418391:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10604.357679] LNet: Removed LNI 192.168.201.47@tcp [10604.587111] Key type .llcrypt unregistered [10604.587975] Key type ._llcrypt unregistered [10604.803218] Key type ._llcrypt registered [10604.804493] Key type .llcrypt registered [10604.986535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10604.993029] alg: No test for adler32 (adler32-zlib) [10605.839422] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10605.915637] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10607.488178] Key type lgssc registered [10607.871604] Lustre: Echo OBD driver; http://www.lustre.org/ [10611.541652] Lustre: DEBUG MARKER: Iteration 31 [10611.652148] LustreError: 419219:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10611.652376] LustreError: 419220:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10611.658721] LustreError: 419219:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10612.712706] Lustre: Mounted lustre-client [10613.180437] LustreError: 419333:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b4ad92800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10613.188036] LustreError: 419333:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10613.191485] LustreError: 419333:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10613.229616] Lustre: Unmounted lustre-client [10614.219787] Key type lgssc unregistered [10614.337522] LNet: 419576:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10614.339622] LNetError: 419576:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10614.347655] LNet: Removed LNI 192.168.201.47@tcp [10614.584134] Key type .llcrypt unregistered [10614.585924] Key type ._llcrypt unregistered [10614.861356] Key type ._llcrypt registered [10614.862576] Key type .llcrypt registered [10615.076211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10615.080772] alg: No test for adler32 (adler32-zlib) [10615.954307] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10616.036952] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10617.624182] Key type lgssc registered [10618.026955] Lustre: Echo OBD driver; http://www.lustre.org/ [10621.621677] Lustre: DEBUG MARKER: Iteration 32 [10621.733614] LustreError: 420406:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10621.733681] LustreError: 420405:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10621.740351] LustreError: 420406:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10622.796565] Lustre: Mounted lustre-client [10623.263024] LustreError: 420514:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44c1c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10623.266689] LustreError: 420514:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10623.269392] LustreError: 420515:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10623.272384] LustreError: 420515:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10623.305598] Lustre: Unmounted lustre-client [10624.285748] Key type lgssc unregistered [10624.406628] LNet: 420755:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10624.408834] LNetError: 420755:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10624.416732] LNet: Removed LNI 192.168.201.47@tcp [10624.648124] Key type .llcrypt unregistered [10624.649652] Key type ._llcrypt unregistered [10624.917777] Key type ._llcrypt registered [10624.922569] Key type .llcrypt registered [10625.111572] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10625.116273] alg: No test for adler32 (adler32-zlib) [10625.981095] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10626.079896] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10627.664135] Key type lgssc registered [10628.053324] Lustre: Echo OBD driver; http://www.lustre.org/ [10631.571613] Lustre: DEBUG MARKER: Iteration 33 [10631.665201] LustreError: 421583:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10631.665239] LustreError: 421584:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10631.670382] LustreError: 421583:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10632.720643] Lustre: Mounted lustre-client [10633.168531] LustreError: 421697:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44342800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10633.170177] LustreError: 421696:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10633.171676] LustreError: 421697:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10633.216668] Lustre: Unmounted lustre-client [10633.217924] Lustre: Skipped 1 previous similar message [10634.177968] Key type lgssc unregistered [10634.285525] LNet: 421940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10634.287776] LNetError: 421940:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10634.295717] LNet: Removed LNI 192.168.201.47@tcp [10634.543090] Key type .llcrypt unregistered [10634.544219] Key type ._llcrypt unregistered [10634.811129] Key type ._llcrypt registered [10634.814716] Key type .llcrypt registered [10635.014672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10635.020114] alg: No test for adler32 (adler32-zlib) [10635.875524] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10635.967488] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10637.552149] Key type lgssc registered [10637.964729] Lustre: Echo OBD driver; http://www.lustre.org/ [10641.368971] Lustre: DEBUG MARKER: Iteration 34 [10641.493706] LustreError: 422768:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10641.493827] LustreError: 422769:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10641.498524] LustreError: 422768:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10642.564909] Lustre: Mounted lustre-client [10643.079394] LustreError: 422883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42fde000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10643.082400] LustreError: 422884:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10643.083212] LustreError: 422883:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10643.120056] Lustre: Unmounted lustre-client [10644.170907] Key type lgssc unregistered [10644.290627] LNet: 423125:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10644.294354] LNetError: 423125:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10644.302754] LNet: Removed LNI 192.168.201.47@tcp [10644.545155] Key type .llcrypt unregistered [10644.546240] Key type ._llcrypt unregistered [10644.845231] Key type ._llcrypt registered [10644.846182] Key type .llcrypt registered [10645.015402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10645.020129] alg: No test for adler32 (adler32-zlib) [10645.879159] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10645.969602] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10647.552144] Key type lgssc registered [10647.960232] Lustre: Echo OBD driver; http://www.lustre.org/ [10651.362091] Lustre: DEBUG MARKER: Iteration 35 [10651.458356] LustreError: 423954:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10651.458389] LustreError: 423955:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10651.462261] LustreError: 423954:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10652.509530] Lustre: Mounted lustre-client [10653.015612] LustreError: 424069:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5961d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10653.017444] LustreError: 424068:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10653.018366] LustreError: 424069:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10653.094333] Lustre: Unmounted lustre-client [10653.095363] Lustre: Skipped 1 previous similar message [10654.094705] Key type lgssc unregistered [10654.215297] LNet: 424312:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10654.218652] LNetError: 424312:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10654.233419] LNet: Removed LNI 192.168.201.47@tcp [10654.479106] Key type .llcrypt unregistered [10654.480210] Key type ._llcrypt unregistered [10654.737793] Key type ._llcrypt registered [10654.742501] Key type .llcrypt registered [10654.936993] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10654.942255] alg: No test for adler32 (adler32-zlib) [10655.802166] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10655.885183] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10657.464101] Key type lgssc registered [10657.863360] Lustre: Echo OBD driver; http://www.lustre.org/ [10661.473790] Lustre: DEBUG MARKER: Iteration 36 [10661.568783] LustreError: 425142:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10661.569864] LustreError: 425141:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10661.573156] LustreError: 425142:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10662.625916] Lustre: Mounted lustre-client [10662.627036] Lustre: Skipped 1 previous similar message [10663.073091] LustreError: 425252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b43689000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10663.076166] LustreError: 425252:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10663.078658] LustreError: 425251:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10663.150347] Lustre: Unmounted lustre-client [10663.151879] Lustre: Skipped 1 previous similar message [10664.069715] Key type lgssc unregistered [10664.183321] LNet: 425494:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10664.185458] LNetError: 425494:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10664.192729] LNet: Removed LNI 192.168.201.47@tcp [10664.425081] Key type .llcrypt unregistered [10664.426133] Key type ._llcrypt unregistered [10664.711815] Key type ._llcrypt registered [10664.712894] Key type .llcrypt registered [10664.901715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10664.906929] alg: No test for adler32 (adler32-zlib) [10665.778111] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10665.868043] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10667.456184] Key type lgssc registered [10667.840079] Lustre: Echo OBD driver; http://www.lustre.org/ [10671.314301] Lustre: DEBUG MARKER: Iteration 37 [10671.417906] LustreError: 426324:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10671.418071] LustreError: 426325:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10671.423276] LustreError: 426324:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10672.478177] Lustre: Mounted lustre-client [10672.479826] Lustre: Skipped 1 previous similar message [10672.936688] LustreError: 426439:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5283f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10672.939167] LustreError: 426438:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10672.941579] LustreError: 426439:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10673.015076] Lustre: Unmounted lustre-client [10673.943392] Key type lgssc unregistered [10674.058594] LNet: 426680:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10674.060778] LNetError: 426680:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10674.069757] LNet: Removed LNI 192.168.201.47@tcp [10674.308116] Key type .llcrypt unregistered [10674.309603] Key type ._llcrypt unregistered [10674.543649] Key type ._llcrypt registered [10674.544793] Key type .llcrypt registered [10674.723933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10674.745385] alg: No test for adler32 (adler32-zlib) [10675.593521] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10675.677402] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10677.256130] Key type lgssc registered [10677.648839] Lustre: Echo OBD driver; http://www.lustre.org/ [10681.460474] Lustre: DEBUG MARKER: Iteration 38 [10681.583873] LustreError: 427507:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10681.583934] LustreError: 427508:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10681.590325] LustreError: 427507:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10682.642624] Lustre: Mounted lustre-client [10683.157690] LustreError: 427613:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b52838800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10683.160863] LustreError: 427613:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10683.162079] LustreError: 427614:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10683.164487] LustreError: 427614:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10683.195057] Lustre: Unmounted lustre-client [10684.201085] Key type lgssc unregistered [10684.322472] LNet: 427855:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10684.324759] LNetError: 427855:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10684.331718] LNet: Removed LNI 192.168.201.47@tcp [10684.560090] Key type .llcrypt unregistered [10684.561412] Key type ._llcrypt unregistered [10684.835075] Key type ._llcrypt registered [10684.839501] Key type .llcrypt registered [10685.016512] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10685.021859] alg: No test for adler32 (adler32-zlib) [10685.870201] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10685.964425] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10687.552120] Key type lgssc registered [10687.932842] Lustre: Echo OBD driver; http://www.lustre.org/ [10691.462630] Lustre: DEBUG MARKER: Iteration 39 [10691.565380] LustreError: 428684:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10691.565460] LustreError: 428685:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10691.569218] LustreError: 428684:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10692.624038] Lustre: Mounted lustre-client [10693.073475] LustreError: 428798:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42e80000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10693.077177] LustreError: 428798:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10693.079905] LustreError: 428799:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10693.138244] Lustre: Unmounted lustre-client [10694.103960] Key type lgssc unregistered [10694.223445] LNet: 429041:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10694.226222] LNetError: 429041:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10694.236745] LNet: Removed LNI 192.168.201.47@tcp [10694.487090] Key type .llcrypt unregistered [10694.488156] Key type ._llcrypt unregistered [10694.750931] Key type ._llcrypt registered [10694.752703] Key type .llcrypt registered [10694.949818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10694.956867] alg: No test for adler32 (adler32-zlib) [10695.829791] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10695.934201] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10697.528144] Key type lgssc registered [10698.024060] Lustre: Echo OBD driver; http://www.lustre.org/ [10702.006721] Lustre: DEBUG MARKER: Iteration 40 [10702.128833] LustreError: 429871:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10702.128886] LustreError: 429872:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10702.134119] LustreError: 429871:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10703.186139] Lustre: Mounted lustre-client [10703.650627] LustreError: 429981:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b50c54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10703.653746] LustreError: 429980:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10703.655475] LustreError: 429981:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10703.706079] Lustre: Unmounted lustre-client [10704.653217] Key type lgssc unregistered [10704.768748] LNet: 430223:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10704.772371] LNetError: 430223:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10704.781832] LNet: Removed LNI 192.168.201.47@tcp [10705.038138] Key type .llcrypt unregistered [10705.039169] Key type ._llcrypt unregistered [10705.290597] Key type ._llcrypt registered [10705.291479] Key type .llcrypt registered [10705.549854] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10705.580653] alg: No test for adler32 (adler32-zlib) [10706.441827] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10706.527622] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10708.112185] Key type lgssc registered [10708.502502] Lustre: Echo OBD driver; http://www.lustre.org/ [10712.216289] Lustre: DEBUG MARKER: Iteration 41 [10712.322987] LustreError: 431051:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10712.323101] LustreError: 431052:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10712.328954] LustreError: 431051:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10713.372968] Lustre: Mounted lustre-client [10713.839808] LustreError: 431164:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b52cdc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10713.844775] LustreError: 431164:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10713.847213] LustreError: 431164:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10713.884868] Lustre: Unmounted lustre-client [10714.822550] Key type lgssc unregistered [10714.948720] LNet: 431406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10714.950668] LNetError: 431406:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10714.961389] LNet: Removed LNI 192.168.201.47@tcp [10715.207120] Key type .llcrypt unregistered [10715.208193] Key type ._llcrypt unregistered [10715.491669] Key type ._llcrypt registered [10715.492860] Key type .llcrypt registered [10715.673771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10715.678927] alg: No test for adler32 (adler32-zlib) [10716.538367] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10716.623726] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10718.208136] Key type lgssc registered [10718.569166] Lustre: Echo OBD driver; http://www.lustre.org/ [10721.980205] Lustre: DEBUG MARKER: Iteration 42 [10722.079767] LustreError: 432234:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10722.080916] LustreError: 432235:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10722.083878] LustreError: 432234:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10723.144343] Lustre: Mounted lustre-client [10723.583088] LustreError: 432342:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b50c50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10723.585316] LustreError: 432341:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10723.587961] LustreError: 432342:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10723.633326] Lustre: Unmounted lustre-client [10724.586846] Key type lgssc unregistered [10724.701453] LNet: 432585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10724.703611] LNetError: 432585:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10724.710781] LNet: Removed LNI 192.168.201.47@tcp [10724.962096] Key type .llcrypt unregistered [10724.963117] Key type ._llcrypt unregistered [10725.228939] Key type ._llcrypt registered [10725.230431] Key type .llcrypt registered [10725.416072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10725.421465] alg: No test for adler32 (adler32-zlib) [10726.283216] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10726.385460] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10727.976168] Key type lgssc registered [10728.348635] Lustre: Echo OBD driver; http://www.lustre.org/ [10732.168568] Lustre: DEBUG MARKER: Iteration 43 [10732.277678] LustreError: 433414:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10732.277727] LustreError: 433415:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10732.283557] LustreError: 433414:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10733.340362] Lustre: Mounted lustre-client [10733.765100] LustreError: 433525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b46191800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10733.771333] LustreError: 433525:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10733.821141] Lustre: Unmounted lustre-client [10734.808157] Key type lgssc unregistered [10734.926643] LNet: 433769:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10734.931404] LNetError: 433769:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10734.939902] LNet: Removed LNI 192.168.201.47@tcp [10735.152110] Key type .llcrypt unregistered [10735.153215] Key type ._llcrypt unregistered [10735.417313] Key type ._llcrypt registered [10735.418447] Key type .llcrypt registered [10735.594672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10735.600080] alg: No test for adler32 (adler32-zlib) [10736.450051] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10736.533660] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10738.120107] Key type lgssc registered [10738.475033] Lustre: Echo OBD driver; http://www.lustre.org/ [10741.763397] Lustre: DEBUG MARKER: Iteration 44 [10741.869732] LustreError: 434597:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10741.870186] LustreError: 434598:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10741.875347] LustreError: 434597:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10742.931779] Lustre: Mounted lustre-client [10742.933490] Lustre: Skipped 1 previous similar message [10743.417846] LustreError: 434710:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42fd4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10743.422138] LustreError: 434711:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10743.423074] LustreError: 434710:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10743.459555] Lustre: Unmounted lustre-client [10744.439946] Key type lgssc unregistered [10744.563525] LNet: 434952:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10744.566283] LNetError: 434952:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10744.575888] LNet: Removed LNI 192.168.201.47@tcp [10744.818101] Key type .llcrypt unregistered [10744.819619] Key type ._llcrypt unregistered [10745.114953] Key type ._llcrypt registered [10745.124092] Key type .llcrypt registered [10745.311518] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10745.317038] alg: No test for adler32 (adler32-zlib) [10746.180273] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10746.264692] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10747.848106] Key type lgssc registered [10748.227548] Lustre: Echo OBD driver; http://www.lustre.org/ [10751.895409] Lustre: DEBUG MARKER: Iteration 45 [10751.994274] LustreError: 435781:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10751.995058] LustreError: 435782:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10751.998181] LustreError: 435781:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10753.048543] Lustre: Mounted lustre-client [10753.498988] LustreError: 435895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b42e80800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10753.502508] LustreError: 435894:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10753.502674] LustreError: 435895:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10753.546586] Lustre: Unmounted lustre-client [10754.452298] Key type lgssc unregistered [10754.563553] LNet: 436136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10754.567979] LNetError: 436136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10754.577941] LNet: Removed LNI 192.168.201.47@tcp [10754.808096] Key type .llcrypt unregistered [10754.809227] Key type ._llcrypt unregistered [10755.100771] Key type ._llcrypt registered [10755.102905] Key type .llcrypt registered [10755.305414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10755.310230] alg: No test for adler32 (adler32-zlib) [10756.171217] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10756.254521] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10757.840157] Key type lgssc registered [10758.225709] Lustre: Echo OBD driver; http://www.lustre.org/ [10762.263894] Lustre: DEBUG MARKER: Iteration 46 [10762.370638] LustreError: 436967:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10762.371073] LustreError: 436966:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10762.375052] LustreError: 436967:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10763.430944] Lustre: Mounted lustre-client [10763.871991] LustreError: 437080:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b47245800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10763.873884] LustreError: 437081:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10763.875316] LustreError: 437080:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10763.924857] Lustre: Unmounted lustre-client [10764.852610] Key type lgssc unregistered [10764.964480] LNet: 437324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10764.966831] LNetError: 437324:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10764.976729] LNet: Removed LNI 192.168.201.47@tcp [10765.220099] Key type .llcrypt unregistered [10765.221646] Key type ._llcrypt unregistered [10765.467805] Key type ._llcrypt registered [10765.475285] Key type .llcrypt registered [10765.675068] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10765.680269] alg: No test for adler32 (adler32-zlib) [10766.568967] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10766.655054] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10768.240168] Key type lgssc registered [10768.610396] Lustre: Echo OBD driver; http://www.lustre.org/ [10772.257830] Lustre: DEBUG MARKER: Iteration 47 [10772.355376] LustreError: 438152:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10772.355426] LustreError: 438153:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10772.359224] LustreError: 438152:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10773.413093] Lustre: Mounted lustre-client [10773.987532] LustreError: 438267:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b47195000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10773.990567] LustreError: 438266:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10773.991161] LustreError: 438267:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10774.036202] Lustre: Unmounted lustre-client [10775.075144] Key type lgssc unregistered [10775.204778] LNet: 438509:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10775.207105] LNetError: 438509:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10775.215284] LNet: Removed LNI 192.168.201.47@tcp [10775.486156] Key type .llcrypt unregistered [10775.487729] Key type ._llcrypt unregistered [10775.824826] Key type ._llcrypt registered [10775.844904] Key type .llcrypt registered [10776.072292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10776.078242] alg: No test for adler32 (adler32-zlib) [10776.941306] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10777.028432] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10778.616128] Key type lgssc registered [10778.973792] Lustre: Echo OBD driver; http://www.lustre.org/ [10782.650782] Lustre: DEBUG MARKER: Iteration 48 [10782.758735] LustreError: 439339:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10782.760114] LustreError: 439338:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10782.764028] LustreError: 439339:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10783.813661] Lustre: Mounted lustre-client [10784.275994] LustreError: 439453:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b80684000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10784.279509] LustreError: 439453:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10784.281922] LustreError: 439452:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10784.347611] Lustre: Unmounted lustre-client [10785.270572] Key type lgssc unregistered [10785.386552] LNet: 439694:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10785.388906] LNetError: 439694:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10785.395698] LNet: Removed LNI 192.168.201.47@tcp [10785.631145] Key type .llcrypt unregistered [10785.631983] Key type ._llcrypt unregistered [10785.873877] Key type ._llcrypt registered [10785.874793] Key type .llcrypt registered [10786.075193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10786.080293] alg: No test for adler32 (adler32-zlib) [10786.938595] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10787.023728] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10788.608157] Key type lgssc registered [10789.032864] Lustre: Echo OBD driver; http://www.lustre.org/ [10792.724059] Lustre: DEBUG MARKER: Iteration 49 [10792.821696] LustreError: 440523:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10792.821727] LustreError: 440524:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10792.825615] LustreError: 440523:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10793.880374] Lustre: Mounted lustre-client [10793.881379] Lustre: Skipped 1 previous similar message [10794.333137] LustreError: 440637:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b47194800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10794.336465] LustreError: 440637:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10794.337584] LustreError: 440638:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10794.398341] Lustre: Unmounted lustre-client [10795.344658] Key type lgssc unregistered [10795.466590] LNet: 440879:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10795.468863] LNetError: 440879:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10795.478670] LNet: Removed LNI 192.168.201.47@tcp [10795.730106] Key type .llcrypt unregistered [10795.731843] Key type ._llcrypt unregistered [10795.989294] Key type ._llcrypt registered [10795.991573] Key type .llcrypt registered [10796.177927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10796.182778] alg: No test for adler32 (adler32-zlib) [10797.055395] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10797.140263] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10798.720131] Key type lgssc registered [10799.100366] Lustre: Echo OBD driver; http://www.lustre.org/ [10802.888958] Lustre: DEBUG MARKER: Iteration 50 [10802.987346] LustreError: 441708:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [10802.987376] LustreError: 441709:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [10802.991617] LustreError: 441708:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10804.045433] Lustre: Mounted lustre-client [10804.575255] LustreError: 441821:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b44b97000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10804.578837] LustreError: 441821:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10804.579957] LustreError: 441820:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10804.644391] Lustre: Unmounted lustre-client [10805.583385] Key type lgssc unregistered [10805.695583] LNet: 442062:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10805.698975] LNetError: 442062:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10805.708725] LNet: Removed LNI 192.168.201.47@tcp [10805.962104] Key type .llcrypt unregistered [10805.963227] Key type ._llcrypt unregistered [10806.199352] Key type ._llcrypt registered [10806.200266] Key type .llcrypt registered [10806.419934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10806.425015] alg: No test for adler32 (adler32-zlib) [10807.285555] Lustre: Lustre: Build Version: 2.17.50_72_g285a242 [10807.364573] LNet: Added LNI 192.168.201.47@tcp [8/256/0/180] [10808.944193] Key type lgssc registered [10809.354813] Lustre: Echo OBD driver; http://www.lustre.org/ [10813.394668] Lustre: Mounted lustre-client [10815.594452] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 03:45:19 (1769762719) [10832.352154] Lustre: 443413:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762720/real 1769762720] req@ffff8c8b6f2aca80 x1855730704459648/t0(0) o36->lustre-MDT0000-mdc-ffff8c8b5890c800@192.168.201.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762736 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10832.358948] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10832.368125] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10847.712238] Lustre: 443413:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762736/real 1769762736] req@ffff8c8b6f2aca80 x1855730704459648/t0(0) o36->lustre-MDT0000-mdc-ffff8c8b5890c800@192.168.201.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762752 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10847.721601] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10847.732032] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10864.096087] Lustre: 443413:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762752/real 1769762752] req@ffff8c8b6f2aca80 x1855730704459648/t0(0) o36->lustre-MDT0000-mdc-ffff8c8b5890c800@192.168.201.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762768 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10864.103668] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10864.114286] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10880.480172] Lustre: 443413:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762768/real 1769762768] req@ffff8c8b6f2aca80 x1855730704459648/t0(0) o36->lustre-MDT0000-mdc-ffff8c8b5890c800@192.168.201.147@tcp:12/10 lens 496/440 e 0 to 1 dl 1769762784 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10880.492099] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection to lustre-MDT0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10880.501714] Lustre: lustre-MDT0000-mdc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10881.056092] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 03:46:25 (1769762785) [10886.883304] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 03:46:30 (1769762790) [10890.180139] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 03:46:34 (1769762794) [10892.568640] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 03:46:36 (1769762796) [10899.747747] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 03:46:43 (1769762803) [10912.003678] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 03:46:56 (1769762816) [10914.252812] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 03:46:58 (1769762818) [10914.313389] LustreError: 448187:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [10916.392070] LustreError: 448187:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [10918.743021] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 03:47:02 (1769762822) [10936.800102] Lustre: 442254:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762825/real 1769762825] req@ffff8c8b6bf15c00 x1855730705496064/t0(0) o4->lustre-OST0000-osc-ffff8c8b5890c800@192.168.201.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762841 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10936.800163] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection to lustre-OST0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10936.806341] Lustre: 442254:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [10936.815393] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10953.184164] Lustre: 442253:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762841/real 1769762841] req@ffff8c8b7c68b100 x1855730705496448/t0(0) o4->lustre-OST0000-osc-ffff8c8b5890c800@192.168.201.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762857 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10953.184224] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection to lustre-OST0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10953.190479] Lustre: 442253:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [10953.199139] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10984.928113] Lustre: 442254:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769762873/real 1769762873] req@ffff8c8b6bf15c00 x1855730705496064/t0(0) o4->lustre-OST0000-osc-ffff8c8b5890c800@192.168.201.147@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769762889 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10984.928138] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection to lustre-OST0000 (at 192.168.201.147@tcp) was lost; in progress operations using this service will wait for recovery to complete [10984.939866] Lustre: 442254:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [10984.945145] Lustre: Skipped 1 previous similar message [10984.951947] Lustre: lustre-OST0000-osc-ffff8c8b5890c800: Connection restored to 192.168.201.147@tcp (at 192.168.201.147@tcp) [10984.954335] Lustre: Skipped 1 previous similar message [11008.242716] Lustre: DEBUG MARKER: oleg147-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8c8b5890c800.ost_server_uuid 50 [11008.778960] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8c8b5890c800.ost_server_uuid in FULL state after 0 sec [11009.344102] Lustre: DEBUG MARKER: cleanup: ====================================================== [11009.997843] Lustre: DEBUG MARKER: == sanityn test complete, duration 10744 sec ============= 03:48:33 (1769762913) [11010.569938] Lustre: DEBUG MARKER: === sanityn: start cleanup 03:48:34 (1769762914) === [11065.101078] LustreError: 450235:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b7bce1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11065.103622] LustreError: 450235:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11065.118066] Lustre: Unmounted lustre-client [11066.215456] Lustre: DEBUG MARKER: === sanityn: finish cleanup 03:49:30 (1769762970) === [11066.508426] LustreError: 450539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8c8b5890c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11066.510731] LustreError: 450539:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11066.517538] LustreError: 450539:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11066.518896] LustreError: 450539:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11066.546119] Lustre: Unmounted lustre-client [11105.466313] Key type lgssc unregistered [11105.585355] LNet: 451222:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11105.588944] LNetError: 451222:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11105.595976] LNet: Removed LNI 192.168.201.47@tcp [11105.839108] Key type .llcrypt unregistered [11105.840259] Key type ._llcrypt unregistered