[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 448594517 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001018] APIC: Switch to symmetric I/O mode setup [ 0.003330] x2apic enabled [ 0.004012] Switched APIC routing to physical x2apic. [ 0.005019] kvm-guest: setup PV IPIs [ 0.008340] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009036] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.012061] LSM: Security Framework initializing [ 0.013057] Yama: becoming mindful. [ 0.014061] SELinux: Initializing. [ 0.015088] *** VALIDATE selinux *** [ 0.024657] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.030049] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.031158] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032141] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033125] *** VALIDATE tmpfs *** [ 0.034494] *** VALIDATE proc *** [ 0.036144] *** VALIDATE cgroup *** [ 0.037010] *** VALIDATE cgroup2 *** [ 0.038267] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039145] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041033] Spectre V2 : User space: Vulnerable [ 0.042014] Speculative Store Bypass: Vulnerable [ 0.044920] debug: unmapping init [mem 0xffffffffa6c59000-0xffffffffa6c60fff] [ 0.046198] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.047781] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.048029] ... version: 2 [ 0.049023] ... bit width: 48 [ 0.050014] ... generic registers: 4 [ 0.051014] ... value mask: 0000ffffffffffff [ 0.052019] ... max period: 00007fffffffffff [ 0.053013] ... fixed-purpose events: 3 [ 0.054013] ... event mask: 000000070000000f [ 0.055300] rcu: Hierarchical SRCU implementation. [ 0.057516] smp: Bringing up secondary CPUs ... [ 0.058684] x86: Booting SMP configuration: [ 0.059033] .... node #0, CPUs: #1 #2 #3 [ 0.063617] smp: Brought up 1 node, 4 CPUs [ 0.064984] smpboot: Max logical packages: 1 [ 0.065037] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.251025] node 0 deferred pages initialised in 183ms [ 0.255017] devtmpfs: initialized [ 0.256289] x86/mm: Memory block size: 128MB [ 0.258412] gcov: version magic: 0x41383552 [ 0.261365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.262125] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.263473] pinctrl core: initialized pinctrl subsystem [ 0.264236] [ 0.264778] ************************************************************* [ 0.265025] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.266028] ** ** [ 0.267021] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.268026] ** ** [ 0.269022] ** This means that this kernel is built to expose internal ** [ 0.270021] ** IOMMU data structures, which may compromise security on ** [ 0.271028] ** your system. ** [ 0.272025] ** ** [ 0.273018] ** If you see this message and you are not debugging the ** [ 0.274016] ** kernel, report this immediately to your vendor! ** [ 0.275023] ** ** [ 0.276020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.277019] ************************************************************* [ 0.278976] NET: Registered protocol family 16 [ 0.280509] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.283093] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.285110] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.289134] cpuidle: using governor menu [ 0.291698] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.294545] PCI: Using configuration type 1 for base access [ 0.296145] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.305080] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.307041] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.311111] cryptd: max_cpu_qlen set to 1000 [ 0.314271] ACPI: Added _OSI(Module Device) [ 0.316022] ACPI: Added _OSI(Processor Device) [ 0.318017] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.319013] ACPI: Added _OSI(Processor Aggregator Device) [ 0.323470] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.329555] ACPI: Interpreter enabled [ 0.331068] ACPI: PM: (supports S0 S3 S4 S5) [ 0.332013] ACPI: Using IOAPIC for interrupt routing [ 0.333093] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.337408] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.346395] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.348048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.351022] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.354098] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.359315] acpiphp: Slot [2] registered [ 0.361146] acpiphp: Slot [5] registered [ 0.362089] acpiphp: Slot [6] registered [ 0.363104] acpiphp: Slot [3] registered [ 0.365153] acpiphp: Slot [4] registered [ 0.366101] acpiphp: Slot [7] registered [ 0.368114] acpiphp: Slot [8] registered [ 0.369116] acpiphp: Slot [9] registered [ 0.371126] acpiphp: Slot [10] registered [ 0.372110] acpiphp: Slot [11] registered [ 0.374100] acpiphp: Slot [12] registered [ 0.375135] acpiphp: Slot [13] registered [ 0.377139] acpiphp: Slot [14] registered [ 0.378108] acpiphp: Slot [15] registered [ 0.380117] acpiphp: Slot [16] registered [ 0.381119] acpiphp: Slot [17] registered [ 0.383107] acpiphp: Slot [18] registered [ 0.385152] acpiphp: Slot [19] registered [ 0.386090] acpiphp: Slot [20] registered [ 0.387126] acpiphp: Slot [21] registered [ 0.389107] acpiphp: Slot [22] registered [ 0.390180] acpiphp: Slot [23] registered [ 0.392102] acpiphp: Slot [24] registered [ 0.393144] acpiphp: Slot [25] registered [ 0.395109] acpiphp: Slot [26] registered [ 0.396104] acpiphp: Slot [27] registered [ 0.398094] acpiphp: Slot [28] registered [ 0.399112] acpiphp: Slot [29] registered [ 0.401132] acpiphp: Slot [30] registered [ 0.402131] acpiphp: Slot [31] registered [ 0.404076] PCI host bridge to bus 0000:00 [ 0.405021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.407033] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.409037] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.412031] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.415024] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.417029] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.419166] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.422075] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.425677] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.433663] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.437488] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.441019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.442020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.445025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.446000] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.448702] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.451048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.453757] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.458017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.467017] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.472019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.476600] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.482018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.488018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.502027] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.512480] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.518021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.524022] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.535022] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.546191] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.549545] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.551483] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.554480] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.557349] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.561192] iommu: Default domain type: Passthrough [ 0.563490] SCSI subsystem initialized [ 0.565167] ACPI: bus type USB registered [ 0.567158] usbcore: registered new interface driver usbfs [ 0.569098] usbcore: registered new interface driver hub [ 0.571134] usbcore: registered new device driver usb [ 0.572181] pps_core: LinuxPPS API ver. 1 registered [ 0.574012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.576063] PTP clock support registered [ 0.578230] EDAC MC: Ver: 3.0.0 [ 0.581182] PCI: Using ACPI for IRQ routing [ 0.582897] NetLabel: Initializing [ 0.585018] NetLabel: domain hash size = 128 [ 0.586018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.589165] NetLabel: unlabeled traffic allowed by default [ 0.591210] vgaarb: loaded [ 0.593326] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.595020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.601483] clocksource: Switched to clocksource kvm-clock [ 0.717240] VFS: Disk quotas dquot_6.6.0 [ 0.718905] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.721391] *** VALIDATE ramfs *** [ 0.722674] *** VALIDATE hugetlbfs *** [ 0.724489] pnp: PnP ACPI init [ 0.727035] pnp: PnP ACPI: found 6 devices [ 0.746693] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.750454] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.752801] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.755223] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.758040] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.760729] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.763687] NET: Registered protocol family 2 [ 0.766220] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.771448] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.775488] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.780826] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.784142] TCP: Hash tables configured (established 65536 bind 65536) [ 0.787183] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.790376] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.793406] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.796065] NET: Registered protocol family 1 [ 0.798936] RPC: Registered named UNIX socket transport module. [ 0.801127] RPC: Registered udp transport module. [ 0.803535] RPC: Registered tcp transport module. [ 0.805401] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.807964] NET: Registered protocol family 44 [ 0.809668] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.811936] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.814018] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.816443] PCI: CLS 0 bytes, default 64 [ 0.818345] Unpacking initramfs... [ 2.257432] debug: unmapping init [mem 0xffff8bcb7cc64000-0xffff8bcb7ffcffff] [ 2.262743] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.265244] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.268196] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.779442] Initialise system trusted keyrings [ 2.781071] Key type blacklist registered [ 2.783619] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.792909] zbud: loaded [ 2.796108] *** VALIDATE nfs *** [ 2.797617] *** VALIDATE nfs4 *** [ 2.799408] pstore: using deflate compression [ 2.802576] Platform Keyring initialized [ 2.904353] NET: Registered protocol family 38 [ 2.906570] Key type asymmetric registered [ 2.908419] Asymmetric key parser 'x509' registered [ 2.910531] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.913923] io scheduler mq-deadline registered [ 2.915698] io scheduler kyber registered [ 2.917403] io scheduler bfq registered [ 2.919229] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.922298] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.925232] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.927912] ACPI: Power Button [PWRF] [ 3.026507] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.124176] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.219208] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.254712] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.287529] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.305650] Non-volatile memory driver v1.3 [ 3.307071] Linux agpgart interface v0.103 [ 3.340550] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.343797] vda: detected capacity change from 0 to 68530176 [ 3.359478] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.362576] vdb: detected capacity change from 0 to 1073741824 [ 3.368667] libphy: Fixed MDIO Bus: probed [ 3.377891] usbcore: registered new interface driver usbserial_generic [ 3.380833] usbserial: USB Serial support registered for generic [ 3.383401] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.387641] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.389387] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.391937] mousedev: PS/2 mouse device common for all mice [ 3.394963] rtc_cmos 00:05: RTC can wake from S4 [ 3.397853] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.398605] rtc_cmos 00:05: registered as rtc0 [ 3.403635] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.404561] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.406921] intel_pstate: CPU model not supported [ 3.411926] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.416315] hid: raw HID events driver (C) Jiri Kosina [ 3.418442] usbcore: registered new interface driver usbhid [ 3.420494] usbhid: USB HID core driver [ 3.422219] drop_monitor: Initializing network drop monitor service [ 3.424592] Initializing XFRM netlink socket [ 3.426768] NET: Registered protocol family 10 [ 3.430863] Segment Routing with IPv6 [ 3.432493] NET: Registered protocol family 17 [ 3.435051] mpls_gso: MPLS GSO support [ 3.440119] RAS: Correctable Errors collector initialized. [ 3.443180] AVX version of gcm_enc/dec engaged. [ 3.444854] AES CTR mode by8 optimization enabled [ 3.527429] sched_clock: Marking stable (3527393072, 0)->(4482369466, -954976394) [ 3.531406] registered taskstats version 1 [ 3.533635] Loading compiled-in X.509 certificates [ 3.536204] zswap: loaded using pool lzo/zbud [ 3.560491] Key type big_key registered [ 3.576497] Key type encrypted registered [ 3.578256] ima: No TPM chip found, activating TPM-bypass! [ 3.580494] ima: Allocated hash algorithm: sha1 [ 3.582265] ima: No architecture policies found [ 3.584329] evm: Initialising EVM extended attributes: [ 3.586389] evm: security.selinux [ 3.587650] evm: security.ima [ 3.588748] evm: security.capability [ 3.590148] evm: HMAC attrs: 0x1 [ 3.592706] rtc_cmos 00:05: setting system clock to 2025-11-16 23:50:19 UTC (1763337019) [ 3.598968] debug: unmapping init [mem 0xffffffffa7c03000-0xffffffffa7dfffff] [ 3.602459] debug: unmapping init [mem 0xffffffffa6982000-0xffffffffa6c58fff] [ 3.612563] Write protecting the kernel read-only data: 28672k [ 3.616045] debug: unmapping init [mem 0xffffffffa5003000-0xffffffffa51fffff] [ 3.619090] debug: unmapping init [mem 0xffffffffa5914000-0xffffffffa59fffff] [ 3.662600] 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.671664] systemd[1]: Detected virtualization kvm. [ 3.673676] systemd[1]: Detected architecture x86-64. [ 3.675220] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.715349] systemd[1]: No hostname configured. [ 3.716942] systemd[1]: Set hostname to . [ 3.718780] random: systemd: uninitialized urandom read (16 bytes read) [ 3.720881] systemd[1]: Initializing machine ID from random generator. [ 3.777915] random: ln: uninitialized urandom read (6 bytes read) [ 3.880380] random: systemd: uninitialized urandom read (16 bytes read) [ 3.882724] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.886638] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.893477] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.454900] device-mapper: uevent: version 1.0.3 [ 4.457638] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.069784] random: fast init done Starting dracut initqueue hook... [ 5.096189] virtio_net virtio0 ens2: renamed from eth0 [ 5.124369] scsi host0: ata_piix [ 5.153238] scsi host1: ata_piix [ 5.154935] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.157812] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.467476] dracut-initqueue[601]: RTNETLINK answers: File exists [ 10.094952] random: crng init done [ 10.096408] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.420722] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.511744] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.765814] SELinux: Disabled at runtime. [ 11.827232] 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.836613] systemd[1]: Detected virtualization kvm. [ 11.838833] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.252142] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.255663] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.260523] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.264968] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.270242] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.278329] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.284892] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for det[ 12.311969] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ails. Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting Kernel Debug File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.725551] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.978795] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.980271] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.067145] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.083599] EDAC sbridge: Ver: 1.1.2 [ 14.182054] Key type dns_resolver registered [ 14.490576] NFS: Registering the id_resolver key type [ 14.492827] Key type id_resolver registered [ 14.494787] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ 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 irqbalance daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ 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... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ 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 oleg133-client login: [ 45.411149] libcfs: loading out-of-tree module taints kernel. [ 45.427716] Key type ._llcrypt registered [ 45.429509] Key type .llcrypt registered [ 45.753147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 45.764602] alg: No test for adler32 (adler32-zlib) [ 46.798333] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [ 47.164817] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [ 48.800234] Key type lgssc registered [ 49.512220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 183.087456] Lustre: Mounted lustre-client [ 187.519362] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 204.052943] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing check_logdir /tmp/testlogs/ [ 207.751203] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing yml_node [ 208.867338] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 23s idle [ 211.302897] Lustre: DEBUG MARKER: Client: 2.16.61.50 [ 213.177617] Lustre: DEBUG MARKER: MDS: 2.16.61.50 [ 215.179440] Lustre: DEBUG MARKER: OSS: 2.16.61.50 [ 216.516491] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 18:53:51 EST 2025 [ 228.300779] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 229.345658] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 230.465956] Lustre: DEBUG MARKER: === sanityn: start setup 18:54:05 (1763337245) === [ 231.113397] Lustre: Mounted lustre-client [ 233.878371] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing check_config_client /mnt/lustre [ 247.273026] hrtimer: interrupt took 9018063 ns [ 247.795446] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 251.876664] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 21s idle [ 251.881310] Lustre: Skipped 1 previous similar message [ 256.519629] Lustre: DEBUG MARKER: === sanityn: finish setup 18:54:31 (1763337271) === [ 258.067712] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 18:54:32 (1763337272) [ 264.417405] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 18:54:39 (1763337279) [ 269.592442] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 18:54:44 (1763337284) [ 274.947889] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 18:54:49 (1763337289) [ 279.309940] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 18:54:54 (1763337294) [ 283.402642] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 18:54:58 (1763337298) [ 287.966477] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 18:55:03 (1763337303) [ 293.210990] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 18:55:07 (1763337307) [ 298.766323] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 18:55:13 (1763337313) [ 304.579753] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 18:55:19 (1763337319) [ 310.686819] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 18:55:25 (1763337325) [ 318.373524] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 18:55:32 (1763337332) [ 323.552354] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 24s idle [ 323.573797] Lustre: Skipped 1 previous similar message [ 325.515188] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 18:55:40 (1763337340) [ 332.110912] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 18:55:46 (1763337346) [ 333.802756] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 22s idle [ 338.033194] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 18:55:52 (1763337352) [ 344.631433] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 18:55:59 (1763337359) [ 351.259170] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 18:56:05 (1763337365) [ 357.675731] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 18:56:12 (1763337372) [ 363.772770] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 18:56:18 (1763337378) [ 368.334442] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 18:56:23 (1763337383) [ 373.882344] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 18:56:28 (1763337388) [ 374.623156] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 517.729095] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 18:58:52 (1763337532) [ 524.790232] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 18:58:59 (1763337539) [ 530.501542] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 18:59:05 (1763337545) [ 536.318667] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 18:59:11 (1763337551) [ 542.332793] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 18:59:16 (1763337556) [ 548.484653] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 18:59:23 (1763337563) [ 549.875661] Lustre: DEBUG MARKER: chmod [ 554.879197] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 18:59:29 (1763337569) [ 1308.655412] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 19:12:03 (1763338323) [ 1449.954348] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 23s idle [ 1524.584911] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 19:15:39 (1763338539) [ 1635.294606] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 19:17:30 (1763338650) [ 1775.248065] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 19:19:49 (1763338789) [ 1808.352365] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 21s idle [ 1810.768681] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 19:20:25 (1763338825) [ 1817.496577] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 19:20:32 (1763338832) [ 1818.682720] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1818.788186] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1818.856553] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1818.909148] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1818.996690] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.070665] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.134400] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.203632] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.266516] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.331397] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.381579] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.457784] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.521497] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.611908] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.699784] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.778441] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.871785] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1819.955641] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.055221] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.134574] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.207317] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.260545] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.365727] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.433287] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.516326] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.576192] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.621548] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.667974] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.750686] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.815332] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.882303] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1820.955705] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.022917] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.081796] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.143663] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.216547] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.277590] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.351972] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.419757] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.484965] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.535723] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.643199] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.719120] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.809496] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1821.891820] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.007387] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.096777] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.182482] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.237158] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.308800] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.400966] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.462783] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.523318] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.614435] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.678055] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.736668] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.858204] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1822.933386] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.009509] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.097054] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.167672] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.235400] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.318037] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.398713] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.508844] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.562446] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.639437] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.685636] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.766430] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.827945] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.885222] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1823.941752] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.002466] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.065115] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.123387] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.188268] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.244393] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.298496] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.388896] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.467741] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.539775] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.611800] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.677591] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.751452] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.798893] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.864889] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1824.951930] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.014040] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.101014] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.218317] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.330480] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.391701] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.463863] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.524196] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.600521] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.674042] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.743973] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.820933] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.932070] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1825.999150] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.068549] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.147833] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.213399] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.293718] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.384822] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.454879] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.557611] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.637112] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.717182] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.834734] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.930620] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1826.995639] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.017528] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.094745] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.175433] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.258812] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.327977] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.408624] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.487092] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.556509] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.605118] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.681920] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.754553] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.804552] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.879141] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.940919] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1827.997935] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.047463] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.129106] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.222273] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.367970] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.474252] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.575783] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.635819] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.751518] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.810366] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.875802] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1828.964183] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.059117] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.137578] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.190276] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.283432] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.365291] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.478922] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.563754] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.658037] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.716689] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.810099] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1829.893255] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.006539] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.083946] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.165652] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.226323] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.279892] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.355127] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.452694] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.541363] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.642952] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.730459] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.820063] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.897371] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1830.964773] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.060216] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.152371] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.213802] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.283139] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.368211] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.447558] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.537727] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.611506] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.680265] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.756579] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.859104] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1831.918192] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.021312] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.124063] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.191902] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.231125] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.322721] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.414086] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.486393] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.569972] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.665479] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.787793] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.900084] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1832.973933] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.070085] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.180172] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.299235] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.385103] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.481742] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.562780] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.634811] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.766443] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.896708] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1833.957793] Lustre: lustre-OST0001-osc-ffff8bcbc68f1000: disconnect after 22s idle [ 1834.020899] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.131535] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.207231] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.280811] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.364249] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.401036] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.470512] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.555781] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.641311] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.714321] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.793181] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.890494] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1834.944068] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.004253] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.075845] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.142994] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.224744] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.327497] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.407924] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.516962] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.604604] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.672122] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.720326] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.771366] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.837611] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.884946] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1835.951733] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.022100] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.094758] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.147468] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.196736] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.276861] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.365028] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.413910] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.496637] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.577232] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.653750] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.739660] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.795685] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.859382] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.906851] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1836.982937] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.031232] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.102396] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.153658] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.227685] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.286841] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.345895] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.415495] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.479750] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.549733] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.617986] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.678502] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1837.749955] rw_seq_cst_vs_d (32390): drop_caches: 3 [ 1839.079822] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 24s idle [ 1845.530416] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 19:21:00 (1763338860) [ 1846.219335] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.364059] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.421626] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.655224] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.742052] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.819650] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1846.942996] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.011321] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.168250] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.332870] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.378505] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.465013] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.578779] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.630558] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.898105] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1847.978942] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.029124] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.054941] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.116417] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.187883] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.297966] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.355907] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.537361] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.573310] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.619903] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.770681] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1848.933164] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.077717] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.216882] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.368762] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.464740] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.686409] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.857387] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1849.963542] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.067902] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.128778] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.182039] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.280228] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.370274] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.426460] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.532892] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.608279] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.724050] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.775261] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.885088] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1850.967855] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.106204] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.159585] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.220294] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.411647] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.511634] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.652482] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.759165] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.863901] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1851.985623] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.072594] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.138905] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.195263] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.319513] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.470524] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.551772] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.644099] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.710147] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.840165] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1852.997994] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.052514] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.180504] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.477241] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.597992] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.843514] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1853.879927] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.000595] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.112807] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.285088] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.328147] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.491054] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.653821] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.705618] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.786625] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.920164] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1854.999703] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.053193] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.401945] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.488095] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.601160] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.619522] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.736212] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1855.872334] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.015932] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.119990] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.205608] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.330975] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.364665] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.479579] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.568485] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.644783] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.679183] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.754120] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.857147] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1856.946956] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.043029] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.174579] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.325697] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.378455] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.495133] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.573214] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.655921] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.789854] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.884469] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1857.914782] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.033257] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.272045] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.356834] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.413863] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.582244] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.704690] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1858.812994] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.048328] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.070142] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.089596] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.108945] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.132497] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.237075] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.300925] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.375817] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.413399] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.555398] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 22s idle [ 1859.577319] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.729824] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1859.895853] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.026170] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.104493] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.246234] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.316830] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.506978] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.538949] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.662390] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.720846] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.772936] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.829571] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.885958] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1860.979491] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.005624] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.089534] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.222448] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.252466] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.395816] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.441161] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.525265] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.653033] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.782822] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1861.927194] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.010727] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.138970] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.215138] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.276687] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.400300] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.429048] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.567922] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.601538] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.726688] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.898905] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1862.976988] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.037176] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.144961] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.223791] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.279397] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.313830] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.402375] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.438500] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.519024] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.701349] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.816486] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.849269] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1863.903148] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.019321] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.093903] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.219348] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.244959] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.381206] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.482394] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.697114] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.746938] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.801690] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1864.975803] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.083670] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.200882] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.404139] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.626969] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.722528] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1865.789569] rw_seq_cst_vs_d (32975): drop_caches: 3 [ 1872.263771] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 19:21:26 (1763338886) [ 1878.470931] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 19:21:33 (1763338893) [ 1883.434433] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 19:21:38 (1763338898) [ 1909.945491] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 19:22:04 (1763338924) [ 1910.318920] bash (35459): drop_caches: 3 [ 1913.553881] bash (35459): drop_caches: 3 [ 1916.824917] bash (35459): drop_caches: 3 [ 1919.974917] bash (35459): drop_caches: 3 [ 1923.135357] bash (35459): drop_caches: 3 [ 1926.262114] bash (35459): drop_caches: 3 [ 1929.418623] bash (35459): drop_caches: 3 [ 1932.824620] bash (35459): drop_caches: 3 [ 1936.119420] bash (35459): drop_caches: 3 [ 1939.371913] bash (35459): drop_caches: 3 [ 1942.480600] bash (35459): drop_caches: 3 [ 1945.611588] bash (35459): drop_caches: 3 [ 1950.373370] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 19:22:44 (1763338964) [ 1960.198091] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 19:22:54 (1763338974) [ 1972.193020] Lustre: lustre-OST0001-osc-ffff8bcbc68f1000: disconnect after 22s idle [ 1972.196145] Lustre: Skipped 1 previous similar message [ 1991.895549] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 19:23:26 (1763339006) [ 1998.860775] Lustre: DEBUG MARKER: loop 5 [ 2002.644393] Lustre: DEBUG MARKER: loop 10 [ 2006.562487] Lustre: DEBUG MARKER: loop 15 [ 2008.032475] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 24s idle [ 2010.016647] Lustre: DEBUG MARKER: loop 20 [ 2017.220580] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 19:23:51 (1763339031) [ 2023.266331] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 19:23:58 (1763339038) [ 2029.738757] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 19:24:04 (1763339044) [ 2054.113796] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 24s idle [ 2054.121306] Lustre: Skipped 4 previous similar messages [ 2099.115461] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 19:25:13 (1763339113) [ 2105.626791] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 19:25:20 (1763339120) [ 2112.493697] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 19:25:27 (1763339127) [ 2119.330782] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 19:25:34 (1763339134) [ 2126.353788] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 19:25:41 (1763339141) [ 2134.000116] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 19:25:48 (1763339148) [ 2141.018127] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 19:25:55 (1763339155) [ 2146.894618] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2148.336228] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 19:26:03 (1763339163) [ 2156.740512] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 19:26:11 (1763339171) [ 2157.384413] Lustre: *** cfs_fail_loc=314, val=0*** [ 2158.432314] Lustre: *** cfs_fail_loc=314, val=0*** [ 2158.437185] Lustre: Skipped 2 previous similar messages [ 2163.351712] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 19:26:18 (1763339178) [ 2166.755838] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 24s idle [ 2171.898124] Lustre: *** cfs_fail_loc=314, val=0*** [ 2171.963706] LustreError: lustre-OST0000-osc-ffff8bcbc68f1000: operation ldlm_enqueue to node 192.168.201.133@tcp failed: rc = -107 [ 2171.971421] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2172.001713] LustreError: lustre-OST0000-osc-ffff8bcbc68f1000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2172.010334] LustreError: 46369:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8bcbc68f1000: namespace resource [0x280000401:0x38:0x0].0x0 (ffff8bcbc8635600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2172.022417] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2176.967804] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 19:26:31 (1763339191) [ 2177.300821] LustreError: 46959:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2180.328146] LustreError: 46959:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2185.907109] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 19:26:40 (1763339200) [ 2192.060175] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 19:26:46 (1763339206) [ 2199.577876] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2201.074479] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 19:26:55 (1763339215) [ 2202.512148] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2203.921830] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 19:26:58 (1763339218) [ 2207.718129] Lustre: lustre-MDT0000-mdc-ffff8bcbd0145000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2217.975779] LustreError: MGC192.168.201.133@tcp: Connection to MGS (at 192.168.201.133@tcp) was lost; in progress operations using this service will fail [ 2217.985371] Lustre: Evicted from MGS (at 192.168.201.133@tcp) after server handle changed from 0x4fa866cb8f9fd7b5 to 0x4fa866cb8fab393d [ 2218.004226] Lustre: MGC192.168.201.133@tcp: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2242.428630] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 19:27:37 (1763339257) [ 2276.799191] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 19:28:11 (1763339291) [ 2292.877469] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 19:28:27 (1763339307) [ 2344.939589] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: Connection to lustre-OST0001 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2344.946220] Lustre: Skipped 1 previous similar message [ 2344.965056] LustreError: lustre-OST0001-osc-ffff8bcbc68f1000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2344.972725] LustreError: lustre-OST0001-osc-ffff8bcbd0145000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2344.974578] Lustre: lustre-OST0001-osc-ffff8bcbc68f1000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2344.982377] Lustre: Skipped 3 previous similar messages [ 2360.287896] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2360.307711] Lustre: Skipped 1 previous similar message [ 2360.324782] LustreError: lustre-OST0000-osc-ffff8bcbd0145000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2360.337319] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2366.432333] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 22s idle [ 2376.242776] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2377.712343] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid in FULL state after 0 sec [ 2380.805189] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2382.120302] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid in IDLE state after 0 sec [ 2386.590765] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2387.957850] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid in FULL state after 0 sec [ 2391.845901] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2393.465962] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid in IDLE state after 0 sec [ 2401.513346] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2402.972779] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8bcbc68f1000.ost_server_uuid in FULL state after 0 sec [ 2405.594676] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid 50 [ 2407.104784] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8bcbc68f1000.ost_server_uuid in IDLE state after 0 sec [ 2408.359938] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 19:30:23 (1763339423) [ 2410.788260] Lustre: DEBUG MARKER: Race attempt 0 [ 2413.363814] Lustre: DEBUG MARKER: Wait for 57430 57482 for 60 sec... [ 2479.724403] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 19:31:34 (1763339494) [ 2486.916920] Lustre: DEBUG MARKER: start test - cycle (0) [ 2507.644094] Lustre: DEBUG MARKER: start test - cycle (1) [ 2529.183483] Lustre: DEBUG MARKER: start test - cycle (2) [ 2550.037148] Lustre: DEBUG MARKER: start test - cycle (3) [ 2569.601303] Lustre: DEBUG MARKER: start test - cycle (4) [ 2591.730684] Lustre: DEBUG MARKER: start test - cycle (5) [ 2611.048753] Lustre: DEBUG MARKER: start test - cycle (6) [ 2632.107361] Lustre: DEBUG MARKER: start test - cycle (7) [ 2637.810581] Lustre: lustre-OST0001-osc-ffff8bcbc68f1000: disconnect after 21s idle [ 2637.813569] Lustre: Skipped 3 previous similar messages [ 2653.312704] Lustre: DEBUG MARKER: start test - cycle (8) [ 2673.143287] Lustre: DEBUG MARKER: start test - cycle (9) [ 2692.064504] Lustre: DEBUG MARKER: start test - cycle (10) [ 2719.247132] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 19:35:33 (1763339733) [ 2809.328418] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 19:37:04 (1763339824) [ 2815.493824] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 19:37:10 (1763339830) [ 2823.589164] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 19:37:18 (1763339838) [ 2831.375308] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 19:37:25 (1763339845) [ 2831.844062] Lustre: *** cfs_fail_loc=411, val=0*** [ 2838.676752] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2840.297508] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 19:37:35 (1763339855) [ 2856.899723] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 19:37:51 (1763339871) [ 2874.219514] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 19:38:08 (1763339888) [ 2891.724161] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 19:38:26 (1763339906) [ 2906.821270] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 19:38:41 (1763339921) [ 2919.636737] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 19:38:54 (1763339934) [ 2930.546379] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 19:39:05 (1763339945) [ 2942.405291] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 19:39:16 (1763339956) [ 2955.186941] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 19:39:29 (1763339969) [ 2968.296924] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 19:39:43 (1763339983) [ 2979.910442] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 19:39:54 (1763339994) [ 2991.991146] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 19:40:06 (1763340006) [ 3003.271180] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 19:40:18 (1763340018) [ 3620.832535] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 20s idle [ 3620.840727] Lustre: Skipped 10 previous similar messages [ 3967.926940] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 19:56:22 (1763340982) [ 3982.084367] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 19:56:36 (1763340996) [ 3993.086698] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 19:56:47 (1763341007) [ 4003.146534] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 19:56:57 (1763341017) [ 4014.404638] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 19:57:09 (1763341029) [ 4026.471936] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 19:57:21 (1763341041) [ 4037.997311] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 19:57:32 (1763341052) [ 4049.651049] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 19:57:44 (1763341064) [ 4060.538300] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 19:57:55 (1763341075) [ 4152.000239] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 19:59:26 (1763341166) [ 4164.298899] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 19:59:39 (1763341179) [ 4176.025475] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 19:59:50 (1763341190) [ 4187.960272] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 20:00:02 (1763341202) [ 4199.288939] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 20:00:14 (1763341214) [ 4210.011751] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 20:00:24 (1763341224) [ 4220.909874] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 20:00:35 (1763341235) [ 4233.637619] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 20:00:48 (1763341248) [ 4246.498541] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 20:01:00 (1763341260) [ 4250.592861] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 22s idle [ 4250.596179] Lustre: Skipped 4 previous similar messages [ 4362.138564] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 20:02:56 (1763341376) [ 4880.352281] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 20s idle [ 4880.356600] Lustre: Skipped 7 previous similar messages [ 5415.516987] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 20:20:30 (1763342430) [ 5427.032744] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 20:20:41 (1763342441) [ 5438.828663] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 20:20:53 (1763342453) [ 5450.627140] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 20:21:05 (1763342465) [ 5462.062168] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 20:21:16 (1763342476) [ 5473.340469] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 20:21:28 (1763342488) [ 5484.232531] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 20:21:39 (1763342499) [ 5496.090138] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 20:21:50 (1763342510) [ 5508.709412] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 20:22:03 (1763342523) [ 5519.265486] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 20:22:14 (1763342534) [ 5525.472410] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 21s idle [ 5525.480577] Lustre: Skipped 3 previous similar messages [ 5666.969654] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 20:24:41 (1763342681) [ 5677.316886] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 20:24:52 (1763342692) [ 5687.843838] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 20:25:02 (1763342702) [ 5697.222492] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 20:25:12 (1763342712) [ 5708.355446] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 20:25:22 (1763342722) [ 5718.949810] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 20:25:33 (1763342733) [ 5729.827829] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 20:25:44 (1763342744) [ 5740.332409] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 20:25:55 (1763342755) [ 5751.155650] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 20:26:06 (1763342766) [ 6811.443531] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 20:43:45 (1763343825) [ 6820.834316] Lustre: lustre-OST0000-osc-ffff8bcbc68f1000: disconnect after 20s idle [ 6820.837116] Lustre: Skipped 1 previous similar message [ 6823.827176] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 20:43:58 (1763343838) [ 6836.337492] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 20:44:10 (1763343850) [ 6849.415283] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 20:44:23 (1763343863) [ 6861.399724] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 20:44:36 (1763343876) [ 6873.076816] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 20:44:47 (1763343887) [ 6885.049493] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 20:44:59 (1763343899) [ 6896.581400] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 20:45:11 (1763343911) [ 6897.636533] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 22s idle [ 6897.651176] Lustre: Skipped 2 previous similar messages [ 6909.168810] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 20:45:23 (1763343923) [ 6920.793320] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 20:45:35 (1763343935) [ 6935.558905] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 20:45:49 (1763343949) [ 6948.982213] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 20:46:03 (1763343963) [ 6959.807669] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 20:46:14 (1763343974) [ 6970.614898] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 20:46:25 (1763343985) [ 6982.889550] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 20:46:37 (1763343997) [ 6993.608701] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 20:46:48 (1763344008) [ 7005.620591] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 20:47:00 (1763344020) [ 7005.980163] LustreError: 6173:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7008.072381] LustreError: 6173:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7016.631751] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 20:47:11 (1763344031) [ 7025.971977] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 20:47:20 (1763344040) [ 7026.585857] LustreError: 237880:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7030.595518] LustreError: 237880:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7030.636434] LustreError: 237880:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7034.712333] LustreError: 237880:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7034.806271] LustreError: 237887:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7038.898702] LustreError: 237887:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7046.745942] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 20:47:40 (1763344060) [ 7059.757991] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 20:47:54 (1763344074) [ 7066.600754] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 22s idle [ 7066.603798] Lustre: Skipped 4 previous similar messages [ 7069.247439] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 20:48:03 (1763344083) [ 7077.632516] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 20:48:12 (1763344092) [ 7108.325500] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 20:48:43 (1763344123) [ 7120.538825] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 20:48:55 (1763344135) [ 7131.904281] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 20:49:06 (1763344146) [ 7148.226127] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 20:49:22 (1763344162) [ 7161.793452] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 20:49:36 (1763344176) [ 7178.591841] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 20:49:53 (1763344193) [ 7196.690289] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 20:50:11 (1763344211) [ 7217.991896] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 20:50:32 (1763344232) [ 7230.304874] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 20:50:44 (1763344244) [ 7320.382642] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 20:52:14 (1763344334) [ 7328.602185] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7336.717701] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 20:52:31 (1763344351) [ 7343.762505] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 20:52:38 (1763344358) [ 7350.721189] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 20:52:45 (1763344365) [ 7357.415920] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 20:52:52 (1763344372) [ 7399.795918] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 20:53:34 (1763344414) [ 7419.874445] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 21s idle [ 7434.211803] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 20:54:09 (1763344449) [ 7442.167193] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 20:54:16 (1763344456) [ 7451.700613] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 20:54:25 (1763344465) [ 7455.081823] LustreError: lustre-MDT0000-mdc-ffff8bcbc68f1000: operation ldlm_enqueue to node 192.168.201.133@tcp failed: rc = -35 [ 7462.949214] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 20:54:37 (1763344477) [ 7463.819288] LustreError: 2424:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7465.912146] LustreError: 2424:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7475.584567] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 20:54:50 (1763344490) [ 7591.697501] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 20:56:46 (1763344606) [ 7598.933534] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 20:56:53 (1763344613) [ 7608.203206] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 20:57:03 (1763344623) [ 7620.423951] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 20:57:15 (1763344635) [ 7632.952835] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 20:57:27 (1763344647) [ 7653.547780] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 20:57:48 (1763344668) [ 7678.968582] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 20:58:13 (1763344693) [ 7691.018524] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 20:58:25 (1763344705) [ 7703.658648] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 20:58:38 (1763344718) [ 7724.847901] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 20:58:59 (1763344739) [ 7776.013276] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 20:59:50 (1763344790) [ 7903.754290] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:01:58 (1763344918) [ 8032.708780] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:04:07 (1763345047) [ 8034.273842] Lustre: lustre-OST0000-osc-ffff8bcbd0145000: disconnect after 24s idle [ 8034.282333] Lustre: Skipped 12 previous similar messages [ 8355.567849] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:09:30 (1763345370) [ 8393.661253] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:10:07 (1763345407) [ 8433.524518] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:10:48 (1763345448) [ 8468.957612] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:11:23 (1763345483) [ 8544.900917] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:12:39 (1763345559) [ 8609.285198] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:13:43 (1763345623) [ 8658.912254] Lustre: lustre-OST0001-osc-ffff8bcbd0145000: disconnect after 20s idle [ 8658.925302] Lustre: Skipped 15 previous similar messages [ 8714.456468] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:15:29 (1763345729) [ 8717.798614] LustreError: 283165:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd0145000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8717.807992] LustreError: 283165:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8717.841103] Lustre: Unmounted lustre-client [ 8719.647232] LustreError: 283178:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc68f1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8719.651912] LustreError: 283178:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8719.661137] LustreError: 283178:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8719.664105] LustreError: 283178:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8719.720424] Lustre: Unmounted lustre-client [ 8832.812722] Lustre: Mounted lustre-client [ 8835.205264] Lustre: Mounted lustre-client [ 8837.813209] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8924.680932] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 21:18:59 (1763345939) [ 8941.666180] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 21:19:16 (1763345956) [ 8949.911042] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 21:19:24 (1763345964) [ 9007.283539] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 21:20:21 (1763346021) [ 9076.104768] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 21:21:30 (1763346090) [ 9087.193684] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 21:21:41 (1763346101) [ 9214.639946] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 21:23:48 (1763346228) [ 9250.447505] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 21:24:24 (1763346264) [ 9308.431807] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 21:25:22 (1763346322) [ 9318.905471] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 21:25:32 (1763346332) [ 9332.198624] Lustre: lustre-OST0001-osc-ffff8bcbd0141800: disconnect after 24s idle [ 9332.206483] Lustre: Skipped 7 previous similar messages [ 9338.411665] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 21:25:52 (1763346352) [ 9348.815951] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 21:26:03 (1763346363) [ 9351.438863] LustreError: 299980:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x159:0x0]: rc = -5 [ 9351.453303] LustreError: 299980:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9351.971129] LustreError: 299989:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x159:0x0]: rc = -5 [ 9351.984928] LustreError: 299989:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 2 previous similar messages [ 9351.995421] LustreError: 299989:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9352.002915] LustreError: 299989:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 2 previous similar messages [ 9353.216728] LustreError: 300010:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2f:0x0]: rc = -5 [ 9353.241640] LustreError: 300010:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [ 9353.251970] LustreError: 300010:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9353.270097] LustreError: 300010:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 5 previous similar messages [ 9355.481402] LustreError: 300050:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x16b:0x0]: rc = -5 [ 9355.487771] LustreError: 300050:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 9355.504176] LustreError: 300050:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9355.509282] LustreError: 300050:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 11 previous similar messages [ 9359.663171] LustreError: 300130:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x59:0x0]: rc = -5 [ 9359.676813] LustreError: 300130:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 23 previous similar messages [ 9359.687229] LustreError: 300130:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9359.696155] LustreError: 300130:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 23 previous similar messages [ 9367.823774] LustreError: 300261:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x86:0x0]: rc = -5 [ 9367.833309] LustreError: 300261:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 38 previous similar messages [ 9367.840298] LustreError: 300261:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9367.853944] LustreError: 300261:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 38 previous similar messages [ 9384.246615] LustreError: 300518:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xd2:0x0]: rc = -5 [ 9384.263792] LustreError: 300518:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 74 previous similar messages [ 9384.268180] LustreError: 300518:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9384.274669] LustreError: 300518:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 74 previous similar messages [ 9415.674288] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 21:27:10 (1763346430) [ 9422.112546] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 21:27:16 (1763346436) [ 9639.013377] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 21:30:53 (1763346653) [ 9640.739484] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9642.552473] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 21:30:56 (1763346656) [ 9856.047751] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 21:34:30 (1763346870) [ 9862.372448] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 21:34:36 (1763346876) [ 9977.318864] Lustre: lustre-OST0000-osc-ffff8bcbd0141800: disconnect after 22s idle [ 9977.332775] Lustre: Skipped 5 previous similar messages [ 9988.355160] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 21:36:43 (1763347003) [ 9999.544190] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 21:36:54 (1763347014) [10008.167092] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 21:37:03 (1763347023) [10195.522445] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 21:40:10 (1763347210) [10383.551052] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 21:43:18 (1763347398) [10390.374656] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 21:43:24 (1763347404) [10409.042821] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 21:43:43 (1763347423) [10409.302850] Lustre: DEBUG MARKER: write [10409.360416] LustreError: 284893:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10411.382812] Lustre: DEBUG MARKER: kill 331484 [10411.388731] LustreError: 331484:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10414.385218] LustreError: 284893:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10417.432195] LustreError: 331484:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10423.412928] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 21:43:57 (1763347437) [10426.529497] LustreError: 332099:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10436.552317] LustreError: 332099:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [10442.877550] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 21:44:17 (1763347457) [10443.396612] LustreError: 332687:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10445.488134] LustreError: 332687:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [10456.805736] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 21:44:30 (1763347470) [10458.639228] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10460.596698] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 21:44:34 (1763347474) [10467.936639] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 21:44:42 (1763347482) [10475.239194] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 21:44:49 (1763347489) [10482.909330] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 21:44:57 (1763347497) [10489.729328] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 21:45:04 (1763347504) [10496.773326] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 21:45:11 (1763347511) [10503.227892] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 21:45:17 (1763347517) [10510.314390] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 21:45:24 (1763347524) [10519.308170] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 21:45:33 (1763347533) [10521.041900] Lustre: *** cfs_fail_loc=415, val=0*** [10533.196181] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 21:45:47 (1763347547) [10565.564971] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 21:46:20 (1763347580) [10566.249204] LustreError: 290090:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [10566.262944] LustreError: 290090:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [10571.264184] LustreError: 285583:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10581.361026] LustreError: 284892:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10581.367521] LustreError: 284892:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 7 previous similar messages [10590.946689] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 21:46:45 (1763347605) [10601.202829] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 21:46:55 (1763347615) [10610.790335] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 21:47:05 (1763347625) [10619.951586] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 21:47:14 (1763347634) [10631.134757] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 21:47:25 (1763347645) [10644.607879] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 21:47:39 (1763347659) [10645.385893] LustreError: 343435:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [10645.390384] LustreError: 343435:0:(osc_request.c:2943:osc_build_rpc()) Skipped 6 previous similar messages [10649.467859] LustreError: 343435:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 awake [10649.470759] LustreError: 343435:0:(osc_request.c:2943:osc_build_rpc()) Skipped 1 previous similar message [10656.735419] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 21:47:51 (1763347671) [10660.376274] LustreError: 344146:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd0141800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10660.392450] LustreError: 344146:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10660.421753] LustreError: 344146:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10660.424985] LustreError: 344146:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10660.472516] Lustre: Unmounted lustre-client [10662.494248] LustreError: 344166:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcc4c6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10662.502873] LustreError: 344166:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10662.521194] LustreError: 344166:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10662.529153] LustreError: 344166:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10662.592091] Lustre: Unmounted lustre-client [10664.108549] Lustre: DEBUG MARKER: Iteration 1 [10664.518329] LustreError: 344331:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10664.519249] LustreError: 344330:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10664.528404] LustreError: 344331:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [10664.815182] Lustre: Mounted lustre-client [10664.816901] Lustre: Skipped 1 previous similar message [10665.854832] LustreError: 344435:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10665.878758] LustreError: 344435:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10665.893924] LustreError: 344435:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10665.900784] LustreError: 344435:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10665.952398] Lustre: Unmounted lustre-client [10668.760464] Key type lgssc unregistered [10669.037328] LNet: 344684:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10669.050600] LNetError: 344684:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10669.077185] LNet: Removed LNI 192.168.201.33@tcp [10669.920184] Key type .llcrypt unregistered [10669.921935] Key type ._llcrypt unregistered [10670.605165] Key type ._llcrypt registered [10670.610021] Key type .llcrypt registered [10671.203702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10671.214625] alg: No test for adler32 (adler32-zlib) [10672.440960] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10673.258289] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10675.008965] Key type lgssc registered [10676.585596] Lustre: Echo OBD driver; http://www.lustre.org/ [10691.236411] Lustre: DEBUG MARKER: Iteration 2 [10691.737932] LustreError: 345515:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10691.741483] LustreError: 345517:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10691.771205] LustreError: 345515:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4976 [10693.134252] Lustre: Mounted lustre-client [10693.143040] Lustre: Skipped 1 previous similar message [10694.450494] LustreError: 345636:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10694.461717] LustreError: 345635:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10694.466117] LustreError: 345636:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10694.539084] Lustre: Unmounted lustre-client [10697.014602] Key type lgssc unregistered [10697.291650] LNet: 345879:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10697.294758] LNetError: 345879:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10697.314569] LNet: Removed LNI 192.168.201.33@tcp [10698.126262] Key type .llcrypt unregistered [10698.131252] Key type ._llcrypt unregistered [10698.916391] Key type ._llcrypt registered [10698.918163] Key type .llcrypt registered [10699.476442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10699.508169] alg: No test for adler32 (adler32-zlib) [10700.635242] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10700.908248] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10702.600396] Key type lgssc registered [10704.150320] Lustre: Echo OBD driver; http://www.lustre.org/ [10716.378367] Lustre: DEBUG MARKER: Iteration 3 [10716.811856] LustreError: 346711:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10716.815401] LustreError: 346705:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10716.823288] LustreError: 346711:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10718.219079] Lustre: Mounted lustre-client [10719.497232] LustreError: 346817:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc8f7a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10719.516773] LustreError: 346817:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10719.624263] Lustre: Unmounted lustre-client [10722.195853] Key type lgssc unregistered [10722.434580] LNet: 347068:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10722.440155] LNetError: 347068:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10722.469306] LNet: Removed LNI 192.168.201.33@tcp [10722.987256] Key type .llcrypt unregistered [10722.989177] Key type ._llcrypt unregistered [10723.772236] Key type ._llcrypt registered [10723.773873] Key type .llcrypt registered [10724.101852] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10724.114543] alg: No test for adler32 (adler32-zlib) [10725.278430] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10725.503739] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10727.176173] Key type lgssc registered [10728.363466] Lustre: Echo OBD driver; http://www.lustre.org/ [10740.352753] Lustre: DEBUG MARKER: Iteration 4 [10740.738382] LustreError: 347895:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10740.757932] LustreError: 347904:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10740.765712] LustreError: 347895:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [10742.186040] Lustre: Mounted lustre-client [10743.299768] LustreError: 348010:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc6b40800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10743.352975] LustreError: 348010:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10743.439929] Lustre: Unmounted lustre-client [10746.328398] Key type lgssc unregistered [10746.581194] LNet: 348258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10746.596581] LNetError: 348258:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10746.700909] LNet: Removed LNI 192.168.201.33@tcp [10747.438165] Key type .llcrypt unregistered [10747.440032] Key type ._llcrypt unregistered [10748.300344] Key type ._llcrypt registered [10748.301623] Key type .llcrypt registered [10748.936115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10748.961052] alg: No test for adler32 (adler32-zlib) [10750.049465] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10750.302750] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10752.008191] Key type lgssc registered [10753.244463] Lustre: Echo OBD driver; http://www.lustre.org/ [10765.946234] Lustre: DEBUG MARKER: Iteration 5 [10766.483391] LustreError: 349090:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10766.488882] LustreError: 349089:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10766.504842] LustreError: 349090:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [10767.792824] Lustre: Mounted lustre-client [10769.218810] LustreError: 349198:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd018f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10769.248427] LustreError: 349198:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [10769.363305] Lustre: Unmounted lustre-client [10772.010922] Key type lgssc unregistered [10772.308285] LNet: 349447:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10772.321719] LNetError: 349447:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10772.360294] LNet: Removed LNI 192.168.201.33@tcp [10773.023892] Key type .llcrypt unregistered [10773.037784] Key type ._llcrypt unregistered [10773.882769] Key type ._llcrypt registered [10773.884261] Key type .llcrypt registered [10774.764402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10774.801986] alg: No test for adler32 (adler32-zlib) [10775.901648] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10776.168326] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10777.856208] Key type lgssc registered [10779.312038] Lustre: Echo OBD driver; http://www.lustre.org/ [10793.719626] Lustre: DEBUG MARKER: Iteration 6 [10794.156541] LustreError: 350276:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10794.156622] LustreError: 350273:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10794.164260] LustreError: 350276:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [10795.494441] Lustre: Mounted lustre-client [10795.506830] Lustre: Skipped 1 previous similar message [10797.038349] LustreError: 350386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd0142000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10797.060701] LustreError: 350386:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10797.143097] Lustre: Unmounted lustre-client [10800.696839] Key type lgssc unregistered [10801.022198] LNet: 350635:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10801.025724] LNetError: 350635:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10801.096464] LNet: Removed LNI 192.168.201.33@tcp [10802.016369] Key type .llcrypt unregistered [10802.020251] Key type ._llcrypt unregistered [10803.014742] Key type ._llcrypt registered [10803.023985] Key type .llcrypt registered [10803.511719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10803.524225] alg: No test for adler32 (adler32-zlib) [10804.691993] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10804.987433] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10806.664173] Key type lgssc registered [10808.146914] Lustre: Echo OBD driver; http://www.lustre.org/ [10821.418338] Lustre: DEBUG MARKER: Iteration 7 [10821.756206] LustreError: 351467:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10821.757547] LustreError: 351465:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10821.773463] LustreError: 351467:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [10824.236302] Lustre: Mounted lustre-client [10825.327328] LustreError: 351577:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd926b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10825.353571] LustreError: 351577:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10825.433942] Lustre: Unmounted lustre-client [10828.808899] Key type lgssc unregistered [10829.007063] LNet: 351827:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10829.018566] LNetError: 351827:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10829.056314] LNet: Removed LNI 192.168.201.33@tcp [10829.795310] Key type .llcrypt unregistered [10829.802189] Key type ._llcrypt unregistered [10830.787450] Key type ._llcrypt registered [10830.789286] Key type .llcrypt registered [10831.316668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10831.325643] alg: No test for adler32 (adler32-zlib) [10832.429503] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10832.669310] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10834.440435] Key type lgssc registered [10835.682962] Lustre: Echo OBD driver; http://www.lustre.org/ [10847.545352] Lustre: DEBUG MARKER: Iteration 8 [10848.034837] LustreError: 352658:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10848.042116] LustreError: 352659:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10848.052072] LustreError: 352658:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10849.413356] Lustre: Mounted lustre-client [10850.657675] LustreError: 352766:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbf3cea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10850.679749] LustreError: 352766:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10850.784441] Lustre: Unmounted lustre-client [10853.525360] Key type lgssc unregistered [10853.732717] LNet: 353015:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10853.737497] LNetError: 353015:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10853.751271] LNet: Removed LNI 192.168.201.33@tcp [10854.396173] Key type .llcrypt unregistered [10854.400295] Key type ._llcrypt unregistered [10855.217991] Key type ._llcrypt registered [10855.259317] Key type .llcrypt registered [10855.694948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10855.717397] alg: No test for adler32 (adler32-zlib) [10856.756265] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10856.932244] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10858.600246] Key type lgssc registered [10859.702536] Lustre: Echo OBD driver; http://www.lustre.org/ [10871.470553] Lustre: DEBUG MARKER: Iteration 9 [10872.107492] LustreError: 353846:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10872.110913] LustreError: 353847:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10872.119553] LustreError: 353846:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10874.533484] Lustre: Mounted lustre-client [10875.535853] LustreError: 353954:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcc00537800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10875.557546] LustreError: 353954:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10875.618426] Lustre: Unmounted lustre-client [10878.474677] Key type lgssc unregistered [10878.748549] LNet: 354203:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10878.755240] LNetError: 354203:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10878.778188] LNet: Removed LNI 192.168.201.33@tcp [10879.460171] Key type .llcrypt unregistered [10879.473888] Key type ._llcrypt unregistered [10880.114109] Key type ._llcrypt registered [10880.118359] Key type .llcrypt registered [10880.590499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10880.613488] alg: No test for adler32 (adler32-zlib) [10881.777939] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10882.086754] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10883.784168] Key type lgssc registered [10885.102928] Lustre: Echo OBD driver; http://www.lustre.org/ [10897.965062] Lustre: DEBUG MARKER: Iteration 10 [10898.248549] LustreError: 355034:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10898.250904] LustreError: 355033:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10898.268080] LustreError: 355034:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [10899.552083] Lustre: Mounted lustre-client [10900.707425] LustreError: 355143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbf3cef000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10900.734888] LustreError: 355143:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10900.795334] Lustre: Unmounted lustre-client [10903.302324] Key type lgssc unregistered [10903.534710] LNet: 355393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10903.555454] LNetError: 355393:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10903.574796] LNet: Removed LNI 192.168.201.33@tcp [10904.178267] Key type .llcrypt unregistered [10904.180705] Key type ._llcrypt unregistered [10904.855626] Key type ._llcrypt registered [10904.861026] Key type .llcrypt registered [10905.229130] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10905.248788] alg: No test for adler32 (adler32-zlib) [10906.309695] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10906.545935] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10908.200237] Key type lgssc registered [10909.694503] Lustre: Echo OBD driver; http://www.lustre.org/ [10922.252108] Lustre: DEBUG MARKER: Iteration 11 [10922.545709] LustreError: 356217:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10922.573806] LustreError: 356239:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10922.588883] LustreError: 356217:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4958 [10923.891447] Lustre: Mounted lustre-client [10925.039298] LustreError: 356331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcb236cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10925.065307] LustreError: 356331:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10925.142233] Lustre: Unmounted lustre-client [10928.078066] Key type lgssc unregistered [10928.382401] LNet: 356580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10928.402098] LNetError: 356580:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10928.448861] LNet: Removed LNI 192.168.201.33@tcp [10929.283334] Key type .llcrypt unregistered [10929.288282] Key type ._llcrypt unregistered [10930.663166] Key type ._llcrypt registered [10930.664968] Key type .llcrypt registered [10930.901294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10930.924901] alg: No test for adler32 (adler32-zlib) [10932.045956] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10932.355638] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10934.080186] Key type lgssc registered [10935.603505] Lustre: Echo OBD driver; http://www.lustre.org/ [10948.088188] Lustre: DEBUG MARKER: Iteration 12 [10948.699231] LustreError: 357410:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10948.704387] LustreError: 357411:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10948.718637] LustreError: 357410:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4980 [10950.116554] Lustre: Mounted lustre-client [10951.356676] LustreError: 357518:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbe14bd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10951.384639] LustreError: 357518:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10951.512424] Lustre: Unmounted lustre-client [10954.326597] Key type lgssc unregistered [10954.589876] LNet: 357767:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10954.601655] LNetError: 357767:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10954.617247] LNet: Removed LNI 192.168.201.33@tcp [10955.246244] Key type .llcrypt unregistered [10955.248891] Key type ._llcrypt unregistered [10956.345808] Key type ._llcrypt registered [10956.410893] Key type .llcrypt registered [10956.689234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10956.705453] alg: No test for adler32 (adler32-zlib) [10957.807350] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10958.122447] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10959.816197] Key type lgssc registered [10961.194830] Lustre: Echo OBD driver; http://www.lustre.org/ [10973.734986] Lustre: DEBUG MARKER: Iteration 13 [10974.096601] LustreError: 358596:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10974.100400] LustreError: 358598:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10974.114120] LustreError: 358596:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [10975.466473] Lustre: Mounted lustre-client [10976.848773] LustreError: 358707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc9c9f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10976.871272] LustreError: 358707:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10976.954848] Lustre: Unmounted lustre-client [10979.766133] Key type lgssc unregistered [10980.080118] LNet: 358956:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10980.100907] LNetError: 358956:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10980.118334] LNet: Removed LNI 192.168.201.33@tcp [10980.940428] Key type .llcrypt unregistered [10980.947708] Key type ._llcrypt unregistered [10981.719467] Key type ._llcrypt registered [10981.721140] Key type .llcrypt registered [10982.282066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10982.307898] alg: No test for adler32 (adler32-zlib) [10983.437892] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [10983.616234] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [10985.328206] Key type lgssc registered [10986.400715] Lustre: Echo OBD driver; http://www.lustre.org/ [10996.480710] Lustre: DEBUG MARKER: Iteration 14 [10997.007403] LustreError: 359785:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10997.009590] LustreError: 359786:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10997.021412] LustreError: 359785:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [10998.386067] Lustre: Mounted lustre-client [10998.396755] Lustre: Skipped 1 previous similar message [10999.456956] LustreError: 359904:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10999.481452] LustreError: 359904:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10999.542095] Lustre: Unmounted lustre-client [11002.255263] Key type lgssc unregistered [11002.497760] LNet: 360146:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11002.514815] LNetError: 360146:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11002.541840] LNet: Removed LNI 192.168.201.33@tcp [11003.139162] Key type .llcrypt unregistered [11003.141119] Key type ._llcrypt unregistered [11003.980853] Key type ._llcrypt registered [11003.984530] Key type .llcrypt registered [11004.394080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11004.404171] alg: No test for adler32 (adler32-zlib) [11005.489220] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11005.791569] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11007.520183] Key type lgssc registered [11009.082578] Lustre: Echo OBD driver; http://www.lustre.org/ [11020.307697] Lustre: DEBUG MARKER: Iteration 15 [11020.675985] LustreError: 360976:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11020.676334] LustreError: 360978:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11020.688436] LustreError: 360976:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11021.909357] Lustre: Mounted lustre-client [11023.035234] LustreError: 361094:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd0147800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11023.074926] LustreError: 361094:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11023.081825] LustreError: 361094:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11023.177256] Lustre: Unmounted lustre-client [11025.863149] Key type lgssc unregistered [11026.119872] LNet: 361336:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11026.132077] LNetError: 361336:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11027.177878] LNet: Removed LNI 192.168.201.33@tcp [11027.913288] Key type .llcrypt unregistered [11027.915082] Key type ._llcrypt unregistered [11028.512766] Key type ._llcrypt registered [11028.514453] Key type .llcrypt registered [11029.050167] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11029.060410] alg: No test for adler32 (adler32-zlib) [11030.139715] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11030.331862] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11032.056279] Key type lgssc registered [11033.798125] Lustre: Echo OBD driver; http://www.lustre.org/ [11048.289065] Lustre: DEBUG MARKER: Iteration 16 [11048.910537] LustreError: 362169:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11048.912055] LustreError: 362171:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11048.923812] LustreError: 362169:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11050.225971] Lustre: Mounted lustre-client [11051.744670] LustreError: 362289:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc82cf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11051.781486] LustreError: 362289:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11051.856667] Lustre: Unmounted lustre-client [11054.710213] Key type lgssc unregistered [11054.969083] LNet: 362529:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11054.972282] LNetError: 362529:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11054.998591] LNet: Removed LNI 192.168.201.33@tcp [11055.568191] Key type .llcrypt unregistered [11055.570150] Key type ._llcrypt unregistered [11056.345200] Key type ._llcrypt registered [11056.346794] Key type .llcrypt registered [11056.751086] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11056.772886] alg: No test for adler32 (adler32-zlib) [11057.932361] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11058.123318] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11059.776329] Key type lgssc registered [11060.783052] Lustre: Echo OBD driver; http://www.lustre.org/ [11072.485555] Lustre: DEBUG MARKER: Iteration 17 [11073.104440] LustreError: 363361:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11073.105144] LustreError: 363360:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11073.118676] LustreError: 363361:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11074.511583] Lustre: Mounted lustre-client [11074.513317] Lustre: Skipped 1 previous similar message [11075.823473] LustreError: 363467:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd0143800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11075.847738] LustreError: 363467:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11075.940593] Lustre: Unmounted lustre-client [11078.823317] Key type lgssc unregistered [11079.090288] LNet: 363716:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11079.099292] LNetError: 363716:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11079.128375] LNet: Removed LNI 192.168.201.33@tcp [11079.738247] Key type .llcrypt unregistered [11079.743127] Key type ._llcrypt unregistered [11080.556396] Key type ._llcrypt registered [11080.561685] Key type .llcrypt registered [11081.011846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11081.026568] alg: No test for adler32 (adler32-zlib) [11082.211517] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11082.516694] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11084.216175] Key type lgssc registered [11085.455449] Lustre: Echo OBD driver; http://www.lustre.org/ [11096.743400] Lustre: DEBUG MARKER: Iteration 18 [11097.258311] LustreError: 364546:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11097.260613] LustreError: 364547:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11097.294599] LustreError: 364546:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4977 [11099.570360] Lustre: Mounted lustre-client [11100.780631] LustreError: 364660:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11100.807394] LustreError: 364660:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11100.913474] Lustre: Unmounted lustre-client [11103.366815] Key type lgssc unregistered [11103.600314] LNet: 364904:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11103.607386] LNetError: 364904:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11104.679414] LNet: Removed LNI 192.168.201.33@tcp [11105.511247] Key type .llcrypt unregistered [11105.521131] Key type ._llcrypt unregistered [11106.590428] Key type ._llcrypt registered [11106.604251] Key type .llcrypt registered [11106.775256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11106.788549] alg: No test for adler32 (adler32-zlib) [11107.815568] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11108.040585] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11109.720239] Key type lgssc registered [11111.158351] Lustre: Echo OBD driver; http://www.lustre.org/ [11123.406618] Lustre: DEBUG MARKER: Iteration 19 [11123.682267] LustreError: 365732:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11123.693621] LustreError: 365735:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11123.699346] LustreError: 365732:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11125.045670] Lustre: Mounted lustre-client [11125.056530] Lustre: Skipped 1 previous similar message [11126.214495] LustreError: 365851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc3834800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11126.245380] LustreError: 365851:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11126.258122] LustreError: 365851:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11126.315378] Lustre: Unmounted lustre-client [11128.707493] Key type lgssc unregistered [11128.999986] LNet: 366094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11129.010236] LNetError: 366094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11129.039057] LNet: Removed LNI 192.168.201.33@tcp [11129.653158] Key type .llcrypt unregistered [11129.658877] Key type ._llcrypt unregistered [11130.352615] Key type ._llcrypt registered [11130.354389] Key type .llcrypt registered [11130.740319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11130.751803] alg: No test for adler32 (adler32-zlib) [11131.888312] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11132.109237] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11133.760188] Key type lgssc registered [11134.914799] Lustre: Echo OBD driver; http://www.lustre.org/ [11147.167185] Lustre: DEBUG MARKER: Iteration 20 [11147.720176] LustreError: 366925:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11147.727088] LustreError: 366927:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11147.732670] LustreError: 366925:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11149.073693] Lustre: Mounted lustre-client [11149.076720] Lustre: Skipped 1 previous similar message [11150.796278] LustreError: 367037:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcc00535800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11150.825021] LustreError: 367037:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11150.918845] Lustre: Unmounted lustre-client [11154.030699] Key type lgssc unregistered [11154.404185] LNet: 367284:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11154.415969] LNetError: 367284:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11154.437429] LNet: Removed LNI 192.168.201.33@tcp [11155.408147] Key type .llcrypt unregistered [11155.411500] Key type ._llcrypt unregistered [11156.253244] Key type ._llcrypt registered [11156.256539] Key type .llcrypt registered [11156.841392] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11156.875716] alg: No test for adler32 (adler32-zlib) [11158.013551] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11158.317023] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11159.978804] Key type lgssc registered [11161.349485] Lustre: Echo OBD driver; http://www.lustre.org/ [11171.812606] Lustre: DEBUG MARKER: Iteration 21 [11172.126576] LustreError: 368116:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11172.130259] LustreError: 368117:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11172.140123] LustreError: 368116:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11173.500461] Lustre: Mounted lustre-client [11174.542339] LustreError: 368223:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcb124000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11174.566203] LustreError: 368223:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11174.624774] Lustre: Unmounted lustre-client [11177.373374] Key type lgssc unregistered [11177.738491] LNet: 368472:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11177.755237] LNetError: 368472:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11178.792470] LNet: Removed LNI 192.168.201.33@tcp [11179.387497] Key type .llcrypt unregistered [11179.389982] Key type ._llcrypt unregistered [11180.306308] Key type ._llcrypt registered [11180.309849] Key type .llcrypt registered [11180.491455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11180.510479] alg: No test for adler32 (adler32-zlib) [11181.554724] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11181.743721] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11183.408183] Key type lgssc registered [11184.570634] Lustre: Echo OBD driver; http://www.lustre.org/ [11194.835195] Lustre: DEBUG MARKER: Iteration 22 [11195.150927] LustreError: 369300:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11195.154951] LustreError: 369302:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11195.166972] LustreError: 369300:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11196.489780] Lustre: Mounted lustre-client [11197.669619] LustreError: 369415:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc7da1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11197.698806] LustreError: 369415:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11197.767488] Lustre: Unmounted lustre-client [11200.147830] Key type lgssc unregistered [11200.449089] LNet: 369664:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11200.458347] LNetError: 369664:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11200.483660] LNet: Removed LNI 192.168.201.33@tcp [11201.059442] Key type .llcrypt unregistered [11201.061346] Key type ._llcrypt unregistered [11201.880214] Key type ._llcrypt registered [11201.881864] Key type .llcrypt registered [11202.203289] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11202.214570] alg: No test for adler32 (adler32-zlib) [11203.318638] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11203.576183] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11205.304294] Key type lgssc registered [11206.590939] Lustre: Echo OBD driver; http://www.lustre.org/ [11217.538855] Lustre: DEBUG MARKER: Iteration 23 [11217.980098] LustreError: 370494:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11217.982273] LustreError: 370495:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11217.988787] LustreError: 370494:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11219.212039] Lustre: Mounted lustre-client [11220.526201] LustreError: 370608:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd1890000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11220.544841] LustreError: 370608:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11220.565058] LustreError: 370610:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11220.576396] LustreError: 370610:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11220.634743] Lustre: Unmounted lustre-client [11223.944499] Key type lgssc unregistered [11224.197119] LNet: 370852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11224.209947] LNetError: 370852:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11225.255956] LNet: Removed LNI 192.168.201.33@tcp [11225.795167] Key type .llcrypt unregistered [11225.798510] Key type ._llcrypt unregistered [11226.826972] Key type ._llcrypt registered [11226.828900] Key type .llcrypt registered [11227.182573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11227.203561] alg: No test for adler32 (adler32-zlib) [11228.380057] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11228.658493] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11230.328105] Key type lgssc registered [11231.844461] Lustre: Echo OBD driver; http://www.lustre.org/ [11243.637101] Lustre: DEBUG MARKER: Iteration 24 [11243.991833] LustreError: 371682:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11244.012420] LustreError: 371684:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11244.021985] LustreError: 371682:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4970 [11246.327292] Lustre: Mounted lustre-client [11247.449401] LustreError: 371792:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc5042800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11247.466551] LustreError: 371792:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11247.470130] LustreError: 371792:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11247.511473] Lustre: Unmounted lustre-client [11249.437985] Key type lgssc unregistered [11249.661965] LNet: 372038:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11249.666820] LNetError: 372038:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11250.728706] LNet: Removed LNI 192.168.201.33@tcp [11251.228996] Key type .llcrypt unregistered [11251.230935] Key type ._llcrypt unregistered [11251.710097] Key type ._llcrypt registered [11251.711908] Key type .llcrypt registered [11252.007509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11252.015764] alg: No test for adler32 (adler32-zlib) [11253.013041] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11253.205138] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11254.888163] Key type lgssc registered [11256.311499] Lustre: Echo OBD driver; http://www.lustre.org/ [11268.255797] Lustre: DEBUG MARKER: Iteration 25 [11268.695030] LustreError: 372869:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11268.697577] LustreError: 372872:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11268.724079] LustreError: 372869:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [11270.112332] Lustre: Mounted lustre-client [11271.894538] LustreError: 372976:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc3832800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11271.923441] LustreError: 372976:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11272.042415] Lustre: Unmounted lustre-client [11275.520399] Key type lgssc unregistered [11275.937366] LNet: 373225:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11275.954504] LNetError: 373225:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11275.982352] LNet: Removed LNI 192.168.201.33@tcp [11276.895134] Key type .llcrypt unregistered [11276.899045] Key type ._llcrypt unregistered [11277.483222] Key type ._llcrypt registered [11277.485107] Key type .llcrypt registered [11277.944365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11277.984267] alg: No test for adler32 (adler32-zlib) [11279.233707] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11279.461605] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11281.176207] Key type lgssc registered [11282.333514] Lustre: Echo OBD driver; http://www.lustre.org/ [11293.646351] Lustre: DEBUG MARKER: Iteration 26 [11293.870788] LustreError: 374049:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11293.895748] LustreError: 374064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11293.915410] LustreError: 374049:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4966 [11296.172689] Lustre: Mounted lustre-client [11297.656281] LustreError: 374168:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcb122000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11297.676699] LustreError: 374168:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11297.759640] Lustre: Unmounted lustre-client [11300.730436] Key type lgssc unregistered [11300.998112] LNet: 374412:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11301.015239] LNetError: 374412:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11301.037379] LNet: Removed LNI 192.168.201.33@tcp [11301.646276] Key type .llcrypt unregistered [11301.648172] Key type ._llcrypt unregistered [11302.534474] Key type ._llcrypt registered [11302.536115] Key type .llcrypt registered [11302.901203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11302.914967] alg: No test for adler32 (adler32-zlib) [11304.105076] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11304.339626] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11306.072269] Key type lgssc registered [11307.340481] Lustre: Echo OBD driver; http://www.lustre.org/ [11318.667510] Lustre: DEBUG MARKER: Iteration 27 [11319.211376] LustreError: 375243:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11319.214295] LustreError: 375242:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11319.225864] LustreError: 375243:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11321.609144] Lustre: Mounted lustre-client [11322.905252] LustreError: 375351:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbfffb0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11322.936219] LustreError: 375351:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11323.000602] Lustre: Unmounted lustre-client [11326.598287] Key type lgssc unregistered [11327.003949] LNet: 375601:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11327.022024] LNetError: 375601:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11327.045192] LNet: Removed LNI 192.168.201.33@tcp [11327.918953] Key type .llcrypt unregistered [11327.922980] Key type ._llcrypt unregistered [11328.651830] Key type ._llcrypt registered [11328.657942] Key type .llcrypt registered [11329.291762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11329.314989] alg: No test for adler32 (adler32-zlib) [11330.500126] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11330.895043] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11332.729157] Key type lgssc registered [11334.024487] Lustre: Echo OBD driver; http://www.lustre.org/ [11347.511968] Lustre: DEBUG MARKER: Iteration 28 [11348.009605] LustreError: 376433:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11348.010893] LustreError: 376432:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11348.028133] LustreError: 376433:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11349.339967] Lustre: Mounted lustre-client [11350.579723] LustreError: 376541:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbec1f4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11350.620528] LustreError: 376541:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11350.680118] Lustre: Unmounted lustre-client [11353.216465] Key type lgssc unregistered [11353.517660] LNet: 376790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11353.527654] LNetError: 376790:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11353.558548] LNet: Removed LNI 192.168.201.33@tcp [11354.307279] Key type .llcrypt unregistered [11354.309782] Key type ._llcrypt unregistered [11355.083316] Key type ._llcrypt registered [11355.084695] Key type .llcrypt registered [11355.293459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11355.312749] alg: No test for adler32 (adler32-zlib) [11356.469775] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11356.702827] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11358.360347] Key type lgssc registered [11359.436470] Lustre: Echo OBD driver; http://www.lustre.org/ [11373.641898] Lustre: DEBUG MARKER: Iteration 29 [11374.237631] LustreError: 377620:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11374.241299] LustreError: 377621:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11374.255141] LustreError: 377620:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [11375.664213] Lustre: Mounted lustre-client [11377.214189] LustreError: 377733:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc8270800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11377.255391] LustreError: 377733:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [11377.265737] LustreError: 377733:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11377.330127] Lustre: Unmounted lustre-client [11380.795366] Key type lgssc unregistered [11381.147596] LNet: 377976:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11381.165550] LNetError: 377976:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11381.249382] LNet: Removed LNI 192.168.201.33@tcp [11381.957987] Key type .llcrypt unregistered [11381.965849] Key type ._llcrypt unregistered [11383.059155] Key type ._llcrypt registered [11383.060808] Key type .llcrypt registered [11383.526618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11383.549403] alg: No test for adler32 (adler32-zlib) [11384.782966] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11384.984249] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11386.696166] Key type lgssc registered [11388.122564] Lustre: Echo OBD driver; http://www.lustre.org/ [11399.438685] Lustre: DEBUG MARKER: Iteration 30 [11399.737774] LustreError: 378805:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11399.742310] LustreError: 378807:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11399.779262] LustreError: 378805:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4971 [11401.081130] Lustre: Mounted lustre-client [11402.536423] LustreError: 378916:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11402.582516] LustreError: 378916:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11402.666542] Lustre: Unmounted lustre-client [11405.646401] Key type lgssc unregistered [11405.920285] LNet: 379162:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11405.933874] LNetError: 379162:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11406.952902] LNet: Removed LNI 192.168.201.33@tcp [11407.534159] Key type .llcrypt unregistered [11407.536063] Key type ._llcrypt unregistered [11408.362901] Key type ._llcrypt registered [11408.364883] Key type .llcrypt registered [11408.729762] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11408.740094] alg: No test for adler32 (adler32-zlib) [11409.899617] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11410.197170] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11411.841965] Key type lgssc registered [11413.187660] Lustre: Echo OBD driver; http://www.lustre.org/ [11424.483258] Lustre: DEBUG MARKER: Iteration 31 [11424.831779] LustreError: 379992:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11424.832293] LustreError: 379996:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11424.844349] LustreError: 379992:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11426.097976] Lustre: Mounted lustre-client [11426.105982] Lustre: Skipped 1 previous similar message [11427.107649] LustreError: 380106:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbf7ba7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11427.134464] LustreError: 380106:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11427.204047] Lustre: Unmounted lustre-client [11429.633951] Key type lgssc unregistered [11429.833647] LNet: 380348:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11429.851352] LNetError: 380348:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11430.893572] LNet: Removed LNI 192.168.201.33@tcp [11431.469455] Key type .llcrypt unregistered [11431.471386] Key type ._llcrypt unregistered [11432.332533] Key type ._llcrypt registered [11432.342253] Key type .llcrypt registered [11432.805745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11432.828962] alg: No test for adler32 (adler32-zlib) [11433.944441] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11434.178330] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11435.864200] Key type lgssc registered [11436.924280] Lustre: Echo OBD driver; http://www.lustre.org/ [11447.046824] Lustre: DEBUG MARKER: Iteration 32 [11447.333647] LustreError: 381187:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11447.339387] LustreError: 381188:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11447.349539] LustreError: 381187:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [11448.617564] Lustre: Mounted lustre-client [11448.619180] Lustre: Skipped 1 previous similar message [11449.690742] LustreError: 381301:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11449.712127] LustreError: 381301:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11449.787299] Lustre: Unmounted lustre-client [11449.793774] Lustre: Skipped 1 previous similar message [11452.114568] Key type lgssc unregistered [11452.343227] LNet: 381545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11452.349666] LNetError: 381545:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11452.367125] LNet: Removed LNI 192.168.201.33@tcp [11452.945205] Key type .llcrypt unregistered [11452.951342] Key type ._llcrypt unregistered [11453.786123] Key type ._llcrypt registered [11453.790948] Key type .llcrypt registered [11454.253939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11454.264280] alg: No test for adler32 (adler32-zlib) [11455.321684] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11455.499542] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11457.160197] Key type lgssc registered [11458.449396] Lustre: Echo OBD driver; http://www.lustre.org/ [11470.495183] Lustre: DEBUG MARKER: Iteration 33 [11470.880168] LustreError: 382370:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11470.881187] LustreError: 382381:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11470.900129] LustreError: 382370:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11472.210340] Lustre: Mounted lustre-client [11473.707016] LustreError: 382483:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcb6db000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11473.726958] LustreError: 382483:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11473.854606] Lustre: Unmounted lustre-client [11474.323210] LustreError: 382491:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [11474.325299] LustreError: 382491:0:(obd_class.h:479:obd_check_dev()) Skipped 13 previous similar messages [11474.384130] Lustre: Unmounted lustre-client [11476.945409] Key type lgssc unregistered [11477.203579] LNet: 382732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11477.211632] LNetError: 382732:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11477.229794] LNet: Removed LNI 192.168.201.33@tcp [11477.744160] Key type .llcrypt unregistered [11477.746401] Key type ._llcrypt unregistered [11478.414838] Key type ._llcrypt registered [11478.431984] Key type .llcrypt registered [11478.974282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11478.995647] alg: No test for adler32 (adler32-zlib) [11480.039227] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11480.282520] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11481.968179] Key type lgssc registered [11483.031772] Lustre: Echo OBD driver; http://www.lustre.org/ [11493.072586] Lustre: DEBUG MARKER: Iteration 34 [11493.518533] LustreError: 383562:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11493.523289] LustreError: 383563:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11493.530190] LustreError: 383562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11494.840374] Lustre: Mounted lustre-client [11496.067568] LustreError: 383675:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbd926e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11496.082081] LustreError: 383675:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11496.163393] Lustre: Unmounted lustre-client [11498.697783] Key type lgssc unregistered [11498.948501] LNet: 383917:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11498.963139] LNetError: 383917:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11498.989219] LNet: Removed LNI 192.168.201.33@tcp [11499.571326] Key type .llcrypt unregistered [11499.573543] Key type ._llcrypt unregistered [11500.395824] Key type ._llcrypt registered [11500.397568] Key type .llcrypt registered [11500.809507] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11500.820174] alg: No test for adler32 (adler32-zlib) [11501.883664] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11502.202298] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11503.920175] Key type lgssc registered [11505.105041] Lustre: Echo OBD driver; http://www.lustre.org/ [11516.653929] Lustre: DEBUG MARKER: Iteration 35 [11517.197661] LustreError: 384749:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11517.197681] LustreError: 384750:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11517.216364] LustreError: 384749:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11518.600441] Lustre: Mounted lustre-client [11518.611029] Lustre: Skipped 1 previous similar message [11519.899281] LustreError: 384862:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11519.925418] LustreError: 384862:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11520.037709] Lustre: Unmounted lustre-client [11522.349972] Key type lgssc unregistered [11522.670706] LNet: 385110:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11522.685734] LNetError: 385110:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11522.713285] LNet: Removed LNI 192.168.201.33@tcp [11523.382354] Key type .llcrypt unregistered [11523.387709] Key type ._llcrypt unregistered [11524.276463] Key type ._llcrypt registered [11524.279234] Key type .llcrypt registered [11524.667190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11524.688738] alg: No test for adler32 (adler32-zlib) [11525.769643] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11525.995490] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11527.664471] Key type lgssc registered [11528.929611] Lustre: Echo OBD driver; http://www.lustre.org/ [11539.675180] Lustre: DEBUG MARKER: Iteration 36 [11540.228625] LustreError: 385940:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11540.232921] LustreError: 385941:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11540.265063] LustreError: 385940:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4967 [11541.650160] Lustre: Mounted lustre-client [11543.407429] LustreError: 386050:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcc00530800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11543.418334] LustreError: 386050:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11543.494808] Lustre: Unmounted lustre-client [11546.175958] Key type lgssc unregistered [11546.423314] LNet: 386296:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11546.431392] LNetError: 386296:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11546.458578] LNet: Removed LNI 192.168.201.33@tcp [11547.226553] Key type .llcrypt unregistered [11547.230932] Key type ._llcrypt unregistered [11547.876269] Key type ._llcrypt registered [11547.879576] Key type .llcrypt registered [11548.515042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11548.573915] alg: No test for adler32 (adler32-zlib) [11549.711891] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11550.014243] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11551.720193] Key type lgssc registered [11552.873654] Lustre: Echo OBD driver; http://www.lustre.org/ [11564.366401] Lustre: DEBUG MARKER: Iteration 37 [11564.669870] LustreError: 387127:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11564.671463] LustreError: 387126:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11564.688183] LustreError: 387127:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11565.938504] Lustre: Mounted lustre-client [11567.001992] LustreError: 387235:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11567.020679] LustreError: 387235:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11567.076305] Lustre: Unmounted lustre-client [11569.725434] Key type lgssc unregistered [11570.002271] LNet: 387484:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11570.016032] LNetError: 387484:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11570.038834] LNet: Removed LNI 192.168.201.33@tcp [11570.798165] Key type .llcrypt unregistered [11570.799936] Key type ._llcrypt unregistered [11571.580410] Key type ._llcrypt registered [11571.585174] Key type .llcrypt registered [11571.790393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11571.804308] alg: No test for adler32 (adler32-zlib) [11572.865582] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11573.057664] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11574.728297] Key type lgssc registered [11575.776507] Lustre: Echo OBD driver; http://www.lustre.org/ [11585.659177] Lustre: DEBUG MARKER: Iteration 38 [11585.958754] LustreError: 388316:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11585.959281] LustreError: 388315:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11585.972685] LustreError: 388316:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11587.213860] Lustre: Mounted lustre-client [11588.451801] LustreError: 388424:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc7da6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11588.477730] LustreError: 388424:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11588.540030] Lustre: Unmounted lustre-client [11590.720827] Key type lgssc unregistered [11590.967892] LNet: 388673:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11590.978726] LNetError: 388673:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11590.997139] LNet: Removed LNI 192.168.201.33@tcp [11591.529168] Key type .llcrypt unregistered [11591.530877] Key type ._llcrypt unregistered [11592.178696] Key type ._llcrypt registered [11592.185827] Key type .llcrypt registered [11592.453579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11592.469689] alg: No test for adler32 (adler32-zlib) [11593.500615] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11593.688607] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11595.344720] Key type lgssc registered [11596.382515] Lustre: Echo OBD driver; http://www.lustre.org/ [11605.066228] Lustre: DEBUG MARKER: Iteration 39 [11605.307814] LustreError: 389501:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11605.309040] LustreError: 389503:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11605.323675] LustreError: 389501:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11606.552537] Lustre: Mounted lustre-client [11606.554184] Lustre: Skipped 1 previous similar message [11607.719606] LustreError: 389608:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc82cc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11607.742623] LustreError: 389608:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11607.856101] Lustre: Unmounted lustre-client [11610.075574] Key type lgssc unregistered [11610.332975] LNet: 389857:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11610.342500] LNetError: 389857:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11610.355253] LNet: Removed LNI 192.168.201.33@tcp [11611.008268] Key type .llcrypt unregistered [11611.010216] Key type ._llcrypt unregistered [11611.517288] Key type ._llcrypt registered [11611.520824] Key type .llcrypt registered [11611.939291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11611.960900] alg: No test for adler32 (adler32-zlib) [11613.046559] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11613.262814] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11614.952185] Key type lgssc registered [11615.991864] Lustre: Echo OBD driver; http://www.lustre.org/ [11627.183269] Lustre: DEBUG MARKER: Iteration 40 [11627.647291] LustreError: 390688:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11627.652463] LustreError: 390689:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11627.676766] LustreError: 390688:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [11629.136845] Lustre: Mounted lustre-client [11630.952729] LustreError: 390806:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc7da3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11630.967626] LustreError: 390805:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11630.967892] LustreError: 390806:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11631.104801] Lustre: Unmounted lustre-client [11633.889583] Key type lgssc unregistered [11634.108611] LNet: 391048:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11634.117186] LNetError: 391048:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11634.141310] LNet: Removed LNI 192.168.201.33@tcp [11634.917157] Key type .llcrypt unregistered [11634.922093] Key type ._llcrypt unregistered [11635.817910] Key type ._llcrypt registered [11635.819451] Key type .llcrypt registered [11636.189550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11636.200029] alg: No test for adler32 (adler32-zlib) [11637.327473] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11637.526726] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11639.200178] Key type lgssc registered [11640.852507] Lustre: Echo OBD driver; http://www.lustre.org/ [11650.762238] Lustre: DEBUG MARKER: Iteration 41 [11651.130361] LustreError: 391875:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11651.165725] LustreError: 391895:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11651.169802] LustreError: 391875:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4971 [11653.459860] Lustre: Mounted lustre-client [11654.797772] LustreError: 391999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcb124000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11654.805577] LustreError: 391999:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11654.819047] LustreError: 391998:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11654.905571] Lustre: Unmounted lustre-client [11656.892446] Key type lgssc unregistered [11657.082155] LNet: 392239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11657.089350] LNetError: 392239:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11657.097430] LNet: Removed LNI 192.168.201.33@tcp [11657.576118] Key type .llcrypt unregistered [11657.579228] Key type ._llcrypt unregistered [11658.125530] Key type ._llcrypt registered [11658.127278] Key type .llcrypt registered [11658.492647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11658.504446] alg: No test for adler32 (adler32-zlib) [11659.663492] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11660.007944] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11661.688106] Key type lgssc registered [11662.657149] Lustre: Echo OBD driver; http://www.lustre.org/ [11671.826334] Lustre: DEBUG MARKER: Iteration 42 [11672.150263] LustreError: 393070:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11672.150325] LustreError: 393071:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11672.162790] LustreError: 393070:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11673.334507] Lustre: Mounted lustre-client [11674.377063] LustreError: 393182:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc3a46000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11674.399806] LustreError: 393182:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11674.449736] Lustre: Unmounted lustre-client [11676.602698] Key type lgssc unregistered [11676.815840] LNet: 393432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11676.824556] LNetError: 393432:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11676.837164] LNet: Removed LNI 192.168.201.33@tcp [11677.353632] Key type .llcrypt unregistered [11677.358098] Key type ._llcrypt unregistered [11677.831952] Key type ._llcrypt registered [11677.834620] Key type .llcrypt registered [11678.202697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11678.214494] alg: No test for adler32 (adler32-zlib) [11679.301770] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11679.513289] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11681.192247] Key type lgssc registered [11682.716081] Lustre: Echo OBD driver; http://www.lustre.org/ [11694.338492] Lustre: DEBUG MARKER: Iteration 43 [11694.694196] LustreError: 394263:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11694.698201] LustreError: 394262:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11694.712698] LustreError: 394263:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [11696.100740] Lustre: Mounted lustre-client [11697.189183] LustreError: 394371:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11697.208426] LustreError: 394371:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11697.275091] Lustre: Unmounted lustre-client [11699.252586] Key type lgssc unregistered [11699.459686] LNet: 394622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11699.463979] LNetError: 394622:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11699.481989] LNet: Removed LNI 192.168.201.33@tcp [11699.972389] Key type .llcrypt unregistered [11699.974468] Key type ._llcrypt unregistered [11700.478134] Key type ._llcrypt registered [11700.482528] Key type .llcrypt registered [11700.878278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11700.920738] alg: No test for adler32 (adler32-zlib) [11701.994956] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11702.186655] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11703.840190] Key type lgssc registered [11704.821385] Lustre: Echo OBD driver; http://www.lustre.org/ [11714.221677] Lustre: DEBUG MARKER: Iteration 44 [11714.573781] LustreError: 395451:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11714.574943] LustreError: 395450:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11714.586112] LustreError: 395451:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11715.854831] Lustre: Mounted lustre-client [11717.037085] LustreError: 395557:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbcb122800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11717.058406] LustreError: 395557:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11717.107125] Lustre: Unmounted lustre-client [11719.242539] Key type lgssc unregistered [11719.487289] LNet: 395806:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11719.493119] LNetError: 395806:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11719.512142] LNet: Removed LNI 192.168.201.33@tcp [11720.000257] Key type .llcrypt unregistered [11720.002313] Key type ._llcrypt unregistered [11720.512530] Key type ._llcrypt registered [11720.520525] Key type .llcrypt registered [11720.867734] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11720.882773] alg: No test for adler32 (adler32-zlib) [11721.902370] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11722.092435] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11723.752808] Key type lgssc registered [11724.661665] Lustre: Echo OBD driver; http://www.lustre.org/ [11732.746443] Lustre: DEBUG MARKER: Iteration 45 [11733.039797] LustreError: 396635:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11733.040229] LustreError: 396637:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11733.051474] LustreError: 396635:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11734.258377] Lustre: Mounted lustre-client [11735.303814] LustreError: 396750:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc3831000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11735.316955] LustreError: 396750:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11735.364697] Lustre: Unmounted lustre-client [11737.380886] Key type lgssc unregistered [11737.575306] LNet: 396994:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11737.586227] LNetError: 396994:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11738.601595] LNet: Removed LNI 192.168.201.33@tcp [11739.126334] Key type .llcrypt unregistered [11739.128255] Key type ._llcrypt unregistered [11739.761212] Key type ._llcrypt registered [11739.763222] Key type .llcrypt registered [11740.086418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11740.099061] alg: No test for adler32 (adler32-zlib) [11741.171411] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11741.401208] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11743.064589] Key type lgssc registered [11744.062431] Lustre: Echo OBD driver; http://www.lustre.org/ [11754.029033] Lustre: DEBUG MARKER: Iteration 46 [11754.449298] LustreError: 397824:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11754.451834] LustreError: 397825:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11754.468045] LustreError: 397824:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11755.694782] Lustre: Mounted lustre-client [11756.921740] LustreError: 397938:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc2d2f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11756.936066] LustreError: 397938:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11756.943070] LustreError: 397940:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11756.955716] LustreError: 397940:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11757.014974] Lustre: Unmounted lustre-client [11759.474618] Key type lgssc unregistered [11759.724801] LNet: 398180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11759.735772] LNetError: 398180:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11759.752237] LNet: Removed LNI 192.168.201.33@tcp [11760.345395] Key type .llcrypt unregistered [11760.348312] Key type ._llcrypt unregistered [11760.944924] Key type ._llcrypt registered [11760.951061] Key type .llcrypt registered [11761.346408] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11761.368468] alg: No test for adler32 (adler32-zlib) [11762.422940] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11762.622899] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11764.312260] Key type lgssc registered [11765.130920] Lustre: Echo OBD driver; http://www.lustre.org/ [11774.254801] Lustre: DEBUG MARKER: Iteration 47 [11774.469328] LustreError: 399010:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11774.469990] LustreError: 399011:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11774.481237] LustreError: 399010:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11775.690509] Lustre: Mounted lustre-client [11776.636981] LustreError: 399123:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11776.655732] LustreError: 399123:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11776.699442] Lustre: Unmounted lustre-client [11778.733117] Key type lgssc unregistered [11778.975939] LNet: 399364:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11778.989605] LNetError: 399364:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11779.013472] LNet: Removed LNI 192.168.201.33@tcp [11779.603486] Key type .llcrypt unregistered [11779.605361] Key type ._llcrypt unregistered [11780.178947] Key type ._llcrypt registered [11780.180760] Key type .llcrypt registered [11780.590986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11780.604457] alg: No test for adler32 (adler32-zlib) [11781.625850] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11781.827326] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11783.544286] Key type lgssc registered [11784.626492] Lustre: Echo OBD driver; http://www.lustre.org/ [11793.677510] Lustre: DEBUG MARKER: Iteration 48 [11794.149920] LustreError: 400190:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11794.149972] LustreError: 400191:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11794.161607] LustreError: 400190:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11795.507604] Lustre: Mounted lustre-client [11796.649810] LustreError: 400299:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbf6824000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11796.667990] LustreError: 400299:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11796.721454] Lustre: Unmounted lustre-client [11799.226567] Key type lgssc unregistered [11799.437814] LNet: 400545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11799.452549] LNetError: 400545:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11799.470684] LNet: Removed LNI 192.168.201.33@tcp [11800.074222] Key type .llcrypt unregistered [11800.078828] Key type ._llcrypt unregistered [11800.640519] Key type ._llcrypt registered [11800.648308] Key type .llcrypt registered [11800.957318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11800.970253] alg: No test for adler32 (adler32-zlib) [11802.030129] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11802.234370] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11803.896183] Key type lgssc registered [11805.241852] Lustre: Echo OBD driver; http://www.lustre.org/ [11814.731155] Lustre: DEBUG MARKER: Iteration 49 [11815.189268] LustreError: 401374:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11815.193186] LustreError: 401375:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11815.199043] LustreError: 401374:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11816.496598] Lustre: Mounted lustre-client [11816.509941] Lustre: Skipped 1 previous similar message [11818.055850] LustreError: 401482:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc4df6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11818.089715] LustreError: 401482:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11818.169246] Lustre: Unmounted lustre-client [11820.816595] Key type lgssc unregistered [11821.044260] LNet: 401730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11821.049918] LNetError: 401730:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11821.076290] LNet: Removed LNI 192.168.201.33@tcp [11821.731185] Key type .llcrypt unregistered [11821.734087] Key type ._llcrypt unregistered [11822.741977] Key type ._llcrypt registered [11822.747784] Key type .llcrypt registered [11822.977972] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11822.993795] alg: No test for adler32 (adler32-zlib) [11824.100242] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11824.361986] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11826.176175] Key type lgssc registered [11827.458587] Lustre: Echo OBD driver; http://www.lustre.org/ [11838.767760] Lustre: DEBUG MARKER: Iteration 50 [11839.048945] LustreError: 402562:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11839.049296] LustreError: 402563:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11839.059344] LustreError: 402562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11840.263956] Lustre: Mounted lustre-client [11841.375419] LustreError: 402679:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbca5b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11841.387747] LustreError: 402679:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11841.440977] Lustre: Unmounted lustre-client [11843.611387] Key type lgssc unregistered [11843.864448] LNet: 402922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11843.870363] LNetError: 402922:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11843.892652] LNet: Removed LNI 192.168.201.33@tcp [11844.475305] Key type .llcrypt unregistered [11844.477603] Key type ._llcrypt unregistered [11845.232443] Key type ._llcrypt registered [11845.233732] Key type .llcrypt registered [11845.450480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11845.466157] alg: No test for adler32 (adler32-zlib) [11846.541798] Lustre: Lustre: Build Version: 2.16.61_50_g1f7afa2 [11846.751259] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11848.416937] Key type lgssc registered [11849.488190] Lustre: Echo OBD driver; http://www.lustre.org/ [11859.442842] Lustre: Mounted lustre-client [11865.418212] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:08:00 (1763348880) [11882.976649] Lustre: 404278:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348882/real 1763348882] req@ffff8bcbcad5d500 x1849005296331776/t0(0) o36->lustre-MDT0000-mdc-ffff8bcbc7fd6800@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1763348898 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11882.995693] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [11883.035574] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [11898.336252] Lustre: 404278:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348898/real 1763348898] req@ffff8bcbcad5d500 x1849005296331776/t0(0) o36->lustre-MDT0000-mdc-ffff8bcbc7fd6800@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1763348914 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11898.383353] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [11898.449044] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [11914.721106] Lustre: 404278:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348914/real 1763348914] req@ffff8bcbcad5d500 x1849005296331776/t0(0) o36->lustre-MDT0000-mdc-ffff8bcbc7fd6800@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1763348930 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11914.800115] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [11914.861653] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [11931.104174] Lustre: 404278:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763348930/real 1763348930] req@ffff8bcbcad5d500 x1849005296331776/t0(0) o36->lustre-MDT0000-mdc-ffff8bcbc7fd6800@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1763348946 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11931.145791] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [11931.203900] Lustre: lustre-MDT0000-mdc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [11934.887598] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:09:09 (1763348949) [11946.284464] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:09:20 (1763348960) [11956.691391] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:09:31 (1763348971) [11962.444234] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:09:36 (1763348976) [11984.103329] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:09:58 (1763348998) [12018.473505] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:10:33 (1763349033) [12024.525688] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:10:39 (1763349039) [12046.816215] Lustre: 403112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763349046/real 1763349046] req@ffff8bcbc330b800 x1849005297373952/t0(0) o4->lustre-OST0000-osc-ffff8bcbc7fd6800@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763349062 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12046.816215] Lustre: 403113:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763349046/real 1763349046] req@ffff8bcbc330bb80 x1849005297373312/t0(0) o4->lustre-OST0000-osc-ffff8bcbc7fd6800@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763349062 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12046.817882] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [12046.837645] Lustre: 403112:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [12046.926536] Lustre: 403113:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [12062.176209] Lustre: 403112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763349062/real 1763349062] req@ffff8bcbc330b800 x1849005297373952/t0(0) o4->lustre-OST0000-osc-ffff8bcbc7fd6800@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763349078 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12062.176248] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [12062.188557] Lustre: 403112:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [12062.224354] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [12078.560163] Lustre: 403113:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763349078/real 1763349078] req@ffff8bcbc330bb80 x1849005297373312/t0(0) o4->lustre-OST0000-osc-ffff8bcbc7fd6800@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763349094 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [12078.564572] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [12078.601693] Lustre: 403113:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [12078.634745] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [12094.968391] Lustre: lustre-OST0000-osc-ffff8bcbc7fd6800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [12123.471319] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8bcbc7fd6800.ost_server_uuid 50 [12125.005573] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8bcbc7fd6800.ost_server_uuid in FULL state after 0 sec [12126.884202] Lustre: DEBUG MARKER: cleanup: ====================================================== [12128.576752] Lustre: DEBUG MARKER: == sanityn test complete, duration 11911 sec ============= 22:12:23 (1763349143) [12130.354451] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:12:24 (1763349144) === [12437.174033] LustreError: 410562:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc82cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12437.203633] LustreError: 410562:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12437.269798] Lustre: Unmounted lustre-client [12441.203951] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:17:35 (1763349455) === [12443.117733] LustreError: 410868:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8bcbc7fd6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12443.134330] LustreError: 410868:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12443.164592] LustreError: 410868:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12443.175310] LustreError: 410868:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [12443.251455] Lustre: Unmounted lustre-client [12500.012978] Key type lgssc unregistered [12500.260583] LNet: 411555:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12500.268646] LNetError: 411555:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12500.286942] LNet: Removed LNI 192.168.201.33@tcp [12501.061144] Key type .llcrypt unregistered [12501.062781] Key type ._llcrypt unregistered