[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 446350128 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003107] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008519] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.009019] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.010010] pid_max: default: 32768 minimum: 301 [ 0.012072] LSM: Security Framework initializing [ 0.013053] Yama: becoming mindful. [ 0.014040] SELinux: Initializing. [ 0.015056] *** VALIDATE selinux *** [ 0.022396] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026359] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027129] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028096] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029105] *** VALIDATE tmpfs *** [ 0.030416] *** VALIDATE proc *** [ 0.032056] *** VALIDATE cgroup *** [ 0.033008] *** VALIDATE cgroup2 *** [ 0.034247] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035169] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037028] Spectre V2 : User space: Vulnerable [ 0.038008] Speculative Store Bypass: Vulnerable [ 0.040951] debug: unmapping init [mem 0xffffffffbc659000-0xffffffffbc660fff] [ 0.042882] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043648] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044020] ... version: 2 [ 0.045010] ... bit width: 48 [ 0.046008] ... generic registers: 4 [ 0.047011] ... value mask: 0000ffffffffffff [ 0.048012] ... max period: 00007fffffffffff [ 0.049013] ... fixed-purpose events: 3 [ 0.050009] ... event mask: 000000070000000f [ 0.051259] rcu: Hierarchical SRCU implementation. [ 0.053316] smp: Bringing up secondary CPUs ... [ 0.054489] x86: Booting SMP configuration: [ 0.055019] .... node #0, CPUs: #1 #2 #3 [ 0.058070] smp: Brought up 1 node, 4 CPUs [ 0.060010] smpboot: Max logical packages: 1 [ 0.061014] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.170372] node 0 deferred pages initialised in 107ms [ 0.174181] devtmpfs: initialized [ 0.175219] x86/mm: Memory block size: 128MB [ 0.177741] gcov: version magic: 0x41383552 [ 0.179654] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.180000] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.182324] pinctrl core: initialized pinctrl subsystem [ 0.184131] [ 0.184592] ************************************************************* [ 0.187014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.189010] ** ** [ 0.191014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.194012] ** ** [ 0.196014] ** This means that this kernel is built to expose internal ** [ 0.198014] ** IOMMU data structures, which may compromise security on ** [ 0.200014] ** your system. ** [ 0.203015] ** ** [ 0.205013] ** If you see this message and you are not debugging the ** [ 0.208013] ** kernel, report this immediately to your vendor! ** [ 0.210011] ** ** [ 0.212013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.215014] ************************************************************* [ 0.217605] NET: Registered protocol family 16 [ 0.219399] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.222058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.224068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.229014] cpuidle: using governor menu [ 0.230440] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.233729] PCI: Using configuration type 1 for base access [ 0.236150] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.246050] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.247026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.249094] cryptd: max_cpu_qlen set to 1000 [ 0.251240] ACPI: Added _OSI(Module Device) [ 0.253016] ACPI: Added _OSI(Processor Device) [ 0.255019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.256016] ACPI: Added _OSI(Processor Aggregator Device) [ 0.260785] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.266544] ACPI: Interpreter enabled [ 0.267063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.268016] ACPI: Using IOAPIC for interrupt routing [ 0.269126] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.272483] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.282446] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.283044] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.285018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.288101] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.292885] acpiphp: Slot [2] registered [ 0.295197] acpiphp: Slot [3] registered [ 0.297144] acpiphp: Slot [4] registered [ 0.299113] acpiphp: Slot [5] registered [ 0.301153] acpiphp: Slot [6] registered [ 0.303159] acpiphp: Slot [7] registered [ 0.305109] acpiphp: Slot [8] registered [ 0.307124] acpiphp: Slot [9] registered [ 0.309109] acpiphp: Slot [10] registered [ 0.310146] acpiphp: Slot [11] registered [ 0.313118] acpiphp: Slot [12] registered [ 0.315101] acpiphp: Slot [13] registered [ 0.316110] acpiphp: Slot [14] registered [ 0.318092] acpiphp: Slot [15] registered [ 0.320120] acpiphp: Slot [16] registered [ 0.321124] acpiphp: Slot [17] registered [ 0.323108] acpiphp: Slot [18] registered [ 0.324094] acpiphp: Slot [19] registered [ 0.326103] acpiphp: Slot [20] registered [ 0.327092] acpiphp: Slot [21] registered [ 0.329092] acpiphp: Slot [22] registered [ 0.331094] acpiphp: Slot [23] registered [ 0.332120] acpiphp: Slot [24] registered [ 0.334101] acpiphp: Slot [25] registered [ 0.336093] acpiphp: Slot [26] registered [ 0.337079] acpiphp: Slot [27] registered [ 0.338119] acpiphp: Slot [28] registered [ 0.340091] acpiphp: Slot [29] registered [ 0.341108] acpiphp: Slot [30] registered [ 0.342097] acpiphp: Slot [31] registered [ 0.344048] PCI host bridge to bus 0000:00 [ 0.345015] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.348020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.350019] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.353021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.355029] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.358014] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.360187] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.364000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.367674] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.375022] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.378624] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.381029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.383022] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.385031] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.387520] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.389777] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.393050] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.395310] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.400000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.409762] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.414010] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.419691] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.426025] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.430016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.442013] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.447926] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.452013] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.458016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.471018] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.481131] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.484413] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.486349] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.488360] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.491243] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.498024] iommu: Default domain type: Passthrough [ 0.499594] SCSI subsystem initialized [ 0.501195] ACPI: bus type USB registered [ 0.502169] usbcore: registered new interface driver usbfs [ 0.504093] usbcore: registered new interface driver hub [ 0.506114] usbcore: registered new device driver usb [ 0.508240] pps_core: LinuxPPS API ver. 1 registered [ 0.510015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.512095] PTP clock support registered [ 0.514150] EDAC MC: Ver: 3.0.0 [ 0.516147] PCI: Using ACPI for IRQ routing [ 0.517639] NetLabel: Initializing [ 0.519011] NetLabel: domain hash size = 128 [ 0.520007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.522076] NetLabel: unlabeled traffic allowed by default [ 0.524253] vgaarb: loaded [ 0.525307] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.527013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.537041] clocksource: Switched to clocksource kvm-clock [ 0.642247] VFS: Disk quotas dquot_6.6.0 [ 0.643768] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.646270] *** VALIDATE ramfs *** [ 0.647451] *** VALIDATE hugetlbfs *** [ 0.648932] pnp: PnP ACPI init [ 0.651290] pnp: PnP ACPI: found 6 devices [ 0.668964] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.671837] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.673928] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.676372] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.678712] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.681227] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.684128] NET: Registered protocol family 2 [ 0.686209] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.691269] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.694449] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.699074] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.702154] TCP: Hash tables configured (established 65536 bind 65536) [ 0.704763] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.708767] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.711375] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.713320] NET: Registered protocol family 1 [ 0.716185] RPC: Registered named UNIX socket transport module. [ 0.718318] RPC: Registered udp transport module. [ 0.719946] RPC: Registered tcp transport module. [ 0.720989] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.723103] NET: Registered protocol family 44 [ 0.724587] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.726953] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.729291] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.731755] PCI: CLS 0 bytes, default 64 [ 0.733563] Unpacking initramfs... [ 2.121478] debug: unmapping init [mem 0xffff9ec37cc64000-0xffff9ec37ffcffff] [ 2.125297] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.127645] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.130619] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 2.633151] Initialise system trusted keyrings [ 2.634869] Key type blacklist registered [ 2.637294] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.647208] zbud: loaded [ 2.651260] *** VALIDATE nfs *** [ 2.652572] *** VALIDATE nfs4 *** [ 2.654289] pstore: using deflate compression [ 2.657132] Platform Keyring initialized [ 2.768693] NET: Registered protocol family 38 [ 2.770578] Key type asymmetric registered [ 2.772057] Asymmetric key parser 'x509' registered [ 2.773819] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.777839] io scheduler mq-deadline registered [ 2.780158] io scheduler kyber registered [ 2.782329] io scheduler bfq registered [ 2.784797] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.788141] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.791104] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.794360] ACPI: Power Button [PWRF] [ 2.891090] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.985988] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.084936] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.114901] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.144738] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.149244] Non-volatile memory driver v1.3 [ 3.150737] Linux agpgart interface v0.103 [ 3.184304] virtio_blk virtio1: [vda] 133248 512-byte logical blocks (68.2 MB/65.1 MiB) [ 3.187062] vda: detected capacity change from 0 to 68222976 [ 3.200174] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.203038] vdb: detected capacity change from 0 to 1073741824 [ 3.212628] libphy: Fixed MDIO Bus: probed [ 3.218135] usbcore: registered new interface driver usbserial_generic [ 3.220630] usbserial: USB Serial support registered for generic [ 3.223089] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.227327] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.228968] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.231990] mousedev: PS/2 mouse device common for all mice [ 3.234459] rtc_cmos 00:05: RTC can wake from S4 [ 3.238171] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.241528] rtc_cmos 00:05: registered as rtc0 [ 3.243903] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.245496] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.250912] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.252615] intel_pstate: CPU model not supported [ 3.259631] hid: raw HID events driver (C) Jiri Kosina [ 3.262530] usbcore: registered new interface driver usbhid [ 3.264435] usbhid: USB HID core driver [ 3.266832] drop_monitor: Initializing network drop monitor service [ 3.269325] Initializing XFRM netlink socket [ 3.271214] NET: Registered protocol family 10 [ 3.273661] Segment Routing with IPv6 [ 3.275151] NET: Registered protocol family 17 [ 3.276951] mpls_gso: MPLS GSO support [ 3.282466] RAS: Correctable Errors collector initialized. [ 3.284559] AVX version of gcm_enc/dec engaged. [ 3.286172] AES CTR mode by8 optimization enabled [ 3.356605] sched_clock: Marking stable (3356572504, 0)->(4229554816, -872982312) [ 3.360942] registered taskstats version 1 [ 3.363545] Loading compiled-in X.509 certificates [ 3.366117] zswap: loaded using pool lzo/zbud [ 3.389467] Key type big_key registered [ 3.400321] Key type encrypted registered [ 3.402104] ima: No TPM chip found, activating TPM-bypass! [ 3.404076] ima: Allocated hash algorithm: sha1 [ 3.405501] ima: No architecture policies found [ 3.406926] evm: Initialising EVM extended attributes: [ 3.408578] evm: security.selinux [ 3.409805] evm: security.ima [ 3.410592] evm: security.capability [ 3.411672] evm: HMAC attrs: 0x1 [ 3.414507] rtc_cmos 00:05: setting system clock to 2025-10-10 19:47:26 UTC (1760125646) [ 3.419813] debug: unmapping init [mem 0xffffffffbd603000-0xffffffffbd7fffff] [ 3.422257] debug: unmapping init [mem 0xffffffffbc382000-0xffffffffbc658fff] [ 3.431071] Write protecting the kernel read-only data: 28672k [ 3.434596] debug: unmapping init [mem 0xffffffffbaa03000-0xffffffffbabfffff] [ 3.437307] debug: unmapping init [mem 0xffffffffbb314000-0xffffffffbb3fffff] [ 3.466845] 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.475103] systemd[1]: Detected virtualization kvm. [ 3.477043] systemd[1]: Detected architecture x86-64. [ 3.479129] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.505166] systemd[1]: No hostname configured. [ 3.506816] systemd[1]: Set hostname to . [ 3.508833] random: systemd: uninitialized urandom read (16 bytes read) [ 3.511585] systemd[1]: Initializing machine ID from random generator. [ 3.547902] random: ln: uninitialized urandom read (6 bytes read) [ 3.632262] random: systemd: uninitialized urandom read (16 bytes read) [ 3.634972] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.639900] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.646290] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Initrd Root Device. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ 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.242436] device-mapper: uevent: version 1.0.3 [ 4.244573] 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. [ 4.832850] random: fast init done [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.867629] virtio_net virtio0 ens2: renamed from eth0 [ 4.909090] scsi host0: ata_piix [ 4.926466] scsi host1: ata_piix [ 4.927832] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.929768] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.509199] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.926996] random: crng init done [ 9.928573] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 11.042100] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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... [ 13.086764] printk: systemd: 26 output lines suppressed due to ratelimiting [ 13.598939] SELinux: Disabled at runtime. [ 13.664991] 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) [ 13.675323] systemd[1]: Detected virtualization kvm. [ 13.676732] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 14.711503] systemd[1]: initrd-switch-root.service: Succeeded. [ 14.719423] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 14.727506] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 14.732338] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 14.737192] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 14.770823] systemd[1]: Starting Journal Service... Starting Journal Service... [ 14.789805] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ 15.035399] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] 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 ] Mounted Huge Pages File System. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 15.787161] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.584953] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 16.669123] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 17.140965] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 17.259063] EDAC sbridge: Ver: 1.1.2 [ 19.331923] Key type dns_resolver registered [ 19.850513] NFS: Registering the id_resolver key type [ 19.852128] Key type id_resolver registered [ 19.853406] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started 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. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... 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 Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg305-client login: [ 72.753246] libcfs: loading out-of-tree module taints kernel. [ 72.828672] Key type ._llcrypt registered [ 72.836732] Key type .llcrypt registered [ 73.438659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 73.453638] alg: No test for adler32 (adler32-zlib) [ 74.766690] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 75.614362] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 77.359405] Key type lgssc registered [ 79.008673] Lustre: Echo OBD driver; http://www.lustre.org/ [ 193.800181] Lustre: Mounted lustre-client [ 197.268556] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 211.380095] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing check_logdir /tmp/testlogs/ [ 213.605574] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing yml_node [ 216.250494] Lustre: DEBUG MARKER: Client: 2.16.58.106 [ 217.886309] Lustre: DEBUG MARKER: MDS: 2.16.58.106 [ 219.390236] Lustre: DEBUG MARKER: OSS: 2.16.58.106 [ 219.617589] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 24s idle [ 220.271163] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Oct 10 15:51:02 EDT 2025 [ 229.874895] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 230.680642] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 231.490818] Lustre: DEBUG MARKER: === sanityn: start setup 15:51:13 (1760125873) === [ 231.902690] Lustre: Mounted lustre-client [ 233.931866] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing check_config_client /mnt/lustre [ 246.146330] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 252.384222] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 21s idle [ 252.387611] Lustre: Skipped 1 previous similar message [ 252.987799] Lustre: DEBUG MARKER: === sanityn: finish setup 15:51:35 (1760125895) === [ 254.147984] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 15:51:36 (1760125896) [ 258.540096] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 15:51:40 (1760125900) [ 262.442152] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 15:51:44 (1760125904) [ 266.591148] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 15:51:48 (1760125908) [ 270.818140] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 15:51:53 (1760125913) [ 274.499944] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 15:51:56 (1760125916) [ 278.551113] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 15:52:00 (1760125920) [ 282.774509] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 15:52:04 (1760125924) [ 286.871885] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 15:52:09 (1760125929) [ 291.037773] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 15:52:13 (1760125933) [ 295.615892] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 15:52:17 (1760125937) [ 300.960151] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 15:52:23 (1760125943) [ 305.679474] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 15:52:27 (1760125947) [ 309.780876] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 15:52:32 (1760125952) [ 314.210472] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 15:52:36 (1760125956) [ 318.222419] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 15:52:40 (1760125960) [ 323.012900] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 15:52:45 (1760125965) [ 327.613975] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 15:52:49 (1760125969) [ 332.107144] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 15:52:54 (1760125974) [ 335.837241] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 15:52:58 (1760125978) [ 340.261067] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 15:53:02 (1760125982) [ 340.759217] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 482.896891] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 15:55:24 (1760126124) [ 489.271364] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 15:55:31 (1760126131) [ 494.373938] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 15:55:36 (1760126136) [ 499.413771] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 15:55:41 (1760126141) [ 504.155598] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 15:55:46 (1760126146) [ 508.528250] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 15:55:50 (1760126150) [ 509.792678] Lustre: DEBUG MARKER: chmod [ 514.148499] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 15:55:56 (1760126156) [ 775.923440] hrtimer: interrupt took 2928517 ns [ 1152.485218] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 16:06:34 (1760126794) [ 1153.503577] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 20s idle [ 1153.519409] Lustre: Skipped 1 previous similar message [ 1286.623795] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 24s idle [ 1286.633837] Lustre: Skipped 3 previous similar messages [ 1352.993468] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 16:09:54 (1760126994) [ 1458.989105] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 16:11:41 (1760127101) [ 1589.305385] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 16:13:51 (1760127231) [ 1622.703413] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 16:14:24 (1760127264) [ 1624.544681] Lustre: lustre-OST0001-osc-ffff9ec3d0e4d000: disconnect after 24s idle [ 1628.722415] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 16:14:30 (1760127270) [ 1629.368145] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.424807] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.477961] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.508311] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.559682] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.613526] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.671207] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.734598] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.781122] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.862520] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.927585] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1629.971546] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.028944] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.081200] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.121565] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.182354] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.229554] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.269152] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.331666] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.389677] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.466772] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.521596] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.603923] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.681574] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.743428] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.829929] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.897346] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1630.965661] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.045042] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.093633] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.181214] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.259119] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.308719] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.380854] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.461613] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.523076] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.571905] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.627481] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.689397] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.749938] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.808995] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.870067] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.917014] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1631.973822] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.042144] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.094169] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.183808] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.235067] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.302472] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.368060] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.416867] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.464932] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.519596] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.578515] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.638779] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.694652] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.812408] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.920688] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1632.978804] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.052426] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.138040] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.198490] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.251840] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.311546] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.359286] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.462697] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.534687] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.597411] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.665339] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.721424] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.767495] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.822774] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.923513] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1633.971119] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.022367] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.069734] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.117802] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.159318] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.216464] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.268336] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.339710] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.400827] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.451737] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.496735] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.557192] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.599589] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.651847] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.697369] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.756122] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.817877] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.876832] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.936432] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1634.992583] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.057280] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.107363] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.214477] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.288979] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.373204] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.452841] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.533853] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.607375] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.689187] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.798533] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.879658] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1635.930279] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.001423] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.058666] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.140146] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.243870] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.338749] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.429201] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.507484] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.560503] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.639057] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.719567] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.777424] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.825893] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.843701] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.871935] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1636.943089] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.019908] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.098180] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.158994] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.245699] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.292922] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.366153] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.463339] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.514056] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.569603] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.650310] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.700030] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.783337] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1637.911728] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.004284] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.098358] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.168248] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.311772] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.400407] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.435932] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.475310] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.520955] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.570231] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.634128] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.697176] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.807439] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.877180] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.925787] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1638.998498] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.054392] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.112250] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.148695] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.187419] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.237182] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.311135] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.388286] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.463828] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.528102] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.588951] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.674973] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.752558] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.818817] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.887340] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1639.961364] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.045306] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.113779] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.184074] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.231285] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.281954] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.356820] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.439495] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.517471] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.585822] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.649762] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.703573] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.761404] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.816913] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.902867] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1640.985873] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.066238] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.150644] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.227644] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.276182] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.346789] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.406403] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.469117] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.553920] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.621409] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.679644] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.746524] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.799874] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.861206] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1641.954172] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.009832] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.108022] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.182119] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.257568] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.307563] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.381693] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.442659] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.511403] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.563576] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.633134] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.719310] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.800972] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1642.923732] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.046921] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.169630] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.277575] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.347364] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.417721] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.542764] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.694830] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.775885] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.847590] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.909033] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1643.969773] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.049263] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.089752] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.174679] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.252793] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.340899] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.432090] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.511365] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.591579] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.708467] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.813971] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.891863] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1644.962193] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.026197] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 22s idle [ 1645.069692] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.151228] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.198682] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.314881] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.425581] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.530574] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.639888] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.726950] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.840749] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.911919] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1645.984829] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.081666] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.169888] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.231821] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.320383] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.414428] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.498770] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.590513] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.688095] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.786450] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.832473] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1646.906252] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.008099] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.111955] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.219550] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.350604] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.440256] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.490609] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.596574] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.673644] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.735687] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.804214] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.885572] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1647.975453] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.068472] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.117931] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.189941] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.280935] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.349867] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.455733] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.555181] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.650361] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.722347] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1648.798867] rw_seq_cst_vs_d (32373): drop_caches: 3 [ 1650.145911] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 23s idle [ 1655.741872] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 16:14:57 (1760127297) [ 1656.145908] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.337247] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.390709] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.431060] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.461449] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.732358] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.826903] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.852206] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1656.944650] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.046777] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.115186] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.233837] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.278558] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.367505] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.428899] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.530811] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.580934] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.650805] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.694540] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.749585] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.934627] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1657.965083] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.099034] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.220554] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.326630] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.456758] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.673696] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.739928] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.854431] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.900717] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1658.925903] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.071625] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.119163] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.342978] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.464387] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.596893] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.735680] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.794647] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.848058] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1659.984341] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.036833] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.093613] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.475887] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.678888] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.753660] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.852629] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.944462] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1660.995061] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.054778] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.090770] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.341198] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.365246] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.533065] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.560948] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.655793] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.712591] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.797648] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.864040] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1661.949248] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.058239] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.168412] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.352705] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.409466] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.484773] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.554550] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.637739] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.677762] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.714120] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.796601] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.882201] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1662.982476] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.018300] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.130791] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.246659] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.321354] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.419055] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.484376] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.560414] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.721695] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.750051] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.897888] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.951885] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1663.986567] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.167718] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.229533] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.271331] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.308388] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.341431] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.378173] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.515065] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.551775] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.681433] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.748996] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.804194] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.869044] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.909028] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.948460] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1664.995684] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.041167] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.086778] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.324335] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.362749] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.629795] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.660107] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.687732] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.745714] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.786411] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.896797] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1665.953244] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.023174] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.073647] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.116936] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.176247] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.265311] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.308351] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.461333] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.719244] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.821124] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.859717] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.889445] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.980478] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1666.999680] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.111256] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.148924] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.180108] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.287991] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.401995] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.510401] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.566873] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.602754] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.717484] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.829300] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1667.951627] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.427036] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.551291] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.576489] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.648506] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.682504] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.751512] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.842623] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1668.888574] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.037332] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.160577] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.199556] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.361379] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.395706] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.582256] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.724508] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.780161] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.891704] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1669.924265] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.064377] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.096425] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.208553] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.236473] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.266193] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.299516] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.356524] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.448824] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.540431] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.620588] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.766621] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1670.824400] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.133246] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.204110] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.288181] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.327568] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.378944] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.404318] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.434274] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.601050] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.675869] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.795333] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1671.965690] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.031498] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.191758] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.324435] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.358651] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.514576] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.623409] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.672457] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.706278] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1672.756592] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.026691] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.130927] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.209465] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.310121] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.367207] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.527975] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.721801] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.843139] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.878448] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1673.963432] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.028206] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.100765] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.220736] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.339166] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.466381] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.653657] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.677630] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.786855] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.873514] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1674.990401] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.024313] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.055704] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.171073] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.234902] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.384316] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.505273] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.576519] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.649493] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.738462] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1675.744122] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: disconnect after 21s idle [ 1675.835833] rw_seq_cst_vs_d (32959): drop_caches: 3 [ 1680.942660] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 16:15:23 (1760127323) [ 1686.159581] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 16:15:28 (1760127328) [ 1692.134265] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 16:15:33 (1760127333) [ 1717.964573] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 16:15:59 (1760127359) [ 1718.453960] bash (35451): drop_caches: 3 [ 1721.682357] bash (35451): drop_caches: 3 [ 1724.859813] bash (35451): drop_caches: 3 [ 1728.064514] bash (35451): drop_caches: 3 [ 1731.234259] bash (35451): drop_caches: 3 [ 1734.355472] bash (35451): drop_caches: 3 [ 1737.471369] bash (35451): drop_caches: 3 [ 1740.918642] bash (35451): drop_caches: 3 [ 1744.316438] bash (35451): drop_caches: 3 [ 1747.434589] bash (35451): drop_caches: 3 [ 1750.580146] bash (35451): drop_caches: 3 [ 1753.724084] bash (35451): drop_caches: 3 [ 1758.761511] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 16:16:40 (1760127400) [ 1768.665981] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 16:16:50 (1760127410) [ 1783.263281] Lustre: lustre-OST0001-osc-ffff9ec3d0e4d000: disconnect after 24s idle [ 1783.277511] Lustre: Skipped 1 previous similar message [ 1820.155622] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 16:17:41 (1760127461) [ 1828.855175] Lustre: DEBUG MARKER: loop 5 [ 1833.339017] Lustre: DEBUG MARKER: loop 10 [ 1834.463227] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 24s idle [ 1837.291969] Lustre: DEBUG MARKER: loop 15 [ 1841.539481] Lustre: DEBUG MARKER: loop 20 [ 1847.680955] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 16:18:09 (1760127489) [ 1853.950965] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 16:18:15 (1760127495) [ 1859.460801] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 16:18:21 (1760127501) [ 1929.644283] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 16:19:31 (1760127571) [ 1936.935628] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 16:19:38 (1760127578) [ 1943.509307] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 16:19:45 (1760127585) [ 1950.488471] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 16:19:52 (1760127592) [ 1956.194705] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 16:19:58 (1760127598) [ 1962.645907] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 16:20:04 (1760127604) [ 1969.517902] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 16:20:11 (1760127611) [ 1975.746927] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1977.206874] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 16:20:19 (1760127619) [ 1984.686651] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 16:20:26 (1760127626) [ 1985.090307] Lustre: *** cfs_fail_loc=314, val=0*** [ 1986.144835] Lustre: *** cfs_fail_loc=314, val=0*** [ 1986.146577] Lustre: Skipped 2 previous similar messages [ 1991.429848] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 16:20:33 (1760127633) [ 2000.747784] Lustre: *** cfs_fail_loc=314, val=0*** [ 2000.887788] LustreError: lustre-OST0000-osc-ffff9ec3d0e4d000: operation ldlm_enqueue to node 192.168.203.105@tcp failed: rc = -107 [ 2000.890992] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: Connection to lustre-OST0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2000.922522] LustreError: lustre-OST0000-osc-ffff9ec3d0e4d000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2000.929812] LustreError: 46368:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9ec3d0e4d000: namespace resource [0x280000401:0x38:0x0].0x0 (ffff9ec3c37d0e00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2000.950077] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 2006.151721] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 16:20:48 (1760127648) [ 2006.489936] LustreError: 46958:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2009.511119] LustreError: 46958:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2014.087224] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 16:20:56 (1760127656) [ 2020.048493] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 16:21:02 (1760127662) [ 2025.118881] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2026.179182] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 16:21:08 (1760127668) [ 2027.271105] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2028.494290] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 16:21:10 (1760127670) [ 2034.146924] Lustre: lustre-MDT0000-mdc-ffff9ec3c635f800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2039.273194] LustreError: MGC192.168.203.105@tcp: Connection to MGS (at 192.168.203.105@tcp) was lost; in progress operations using this service will fail [ 2039.289324] Lustre: Evicted from MGS (at 192.168.203.105@tcp) after server handle changed from 0x6eb7c179025504c5 to 0x6eb7c17902626fd4 [ 2039.299503] Lustre: MGC192.168.203.105@tcp: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 2042.277866] Lustre: lustre-MDT0000-mdc-ffff9ec3c635f800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 2044.383966] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 23s idle [ 2044.386394] Lustre: Skipped 5 previous similar messages [ 2061.677630] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 16:21:43 (1760127703) [ 2098.095834] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 16:22:20 (1760127740) [ 2115.376261] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 16:22:37 (1760127757) [ 2167.271414] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: Connection to lustre-OST0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2167.283712] Lustre: Skipped 1 previous similar message [ 2167.291930] LustreError: lustre-OST0000-osc-ffff9ec3d0e4d000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2167.299666] LustreError: lustre-OST0000-osc-ffff9ec3c635f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2167.303607] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 2167.311227] Lustre: Skipped 2 previous similar messages [ 2181.578873] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: Connection to lustre-OST0001 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2181.587892] Lustre: Skipped 1 previous similar message [ 2181.599875] LustreError: lustre-OST0001-osc-ffff9ec3c635f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2181.615334] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 2187.744176] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 20s idle [ 2187.746954] Lustre: Skipped 1 previous similar message [ 2196.425098] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2197.790593] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid in IDLE state after 0 sec [ 2200.985924] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2202.388497] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid in FULL state after 0 sec [ 2207.275762] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2208.553115] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid in IDLE state after 0 sec [ 2211.657646] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2213.122563] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid in IDLE state after 0 sec [ 2222.560236] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2223.978470] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9ec3c635f800.ost_server_uuid in IDLE state after 0 sec [ 2227.956622] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid 50 [ 2229.404820] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9ec3c635f800.ost_server_uuid in IDLE state after 0 sec [ 2231.008868] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 16:24:32 (1760127872) [ 2233.468987] Lustre: DEBUG MARKER: Race attempt 0 [ 2236.834357] Lustre: DEBUG MARKER: Wait for 57533 57583 for 60 sec... [ 2301.909390] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 16:25:43 (1760127943) [ 2309.327971] Lustre: DEBUG MARKER: start test - cycle (0) [ 2327.967670] Lustre: DEBUG MARKER: start test - cycle (1) [ 2352.439260] Lustre: DEBUG MARKER: start test - cycle (2) [ 2375.791642] Lustre: DEBUG MARKER: start test - cycle (3) [ 2398.898726] Lustre: DEBUG MARKER: start test - cycle (4) [ 2419.848477] Lustre: DEBUG MARKER: start test - cycle (5) [ 2444.472358] Lustre: DEBUG MARKER: start test - cycle (6) [ 2448.863236] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 21s idle [ 2448.865709] Lustre: Skipped 4 previous similar messages [ 2465.277939] Lustre: DEBUG MARKER: start test - cycle (7) [ 2485.731938] Lustre: DEBUG MARKER: start test - cycle (8) [ 2506.645513] Lustre: DEBUG MARKER: start test - cycle (9) [ 2527.584800] Lustre: DEBUG MARKER: start test - cycle (10) [ 2552.784732] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 16:29:54 (1760128194) [ 2621.662342] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 16:31:03 (1760128263) [ 2627.433902] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 16:31:09 (1760128269) [ 2633.693807] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 16:31:15 (1760128275) [ 2640.178200] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 16:31:22 (1760128282) [ 2640.456216] Lustre: *** cfs_fail_loc=411, val=0*** [ 2644.999384] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2646.202292] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 16:31:28 (1760128288) [ 2660.167582] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 16:31:42 (1760128302) [ 2672.849725] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 16:31:55 (1760128315) [ 2684.452213] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 16:32:06 (1760128326) [ 2696.313479] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 16:32:18 (1760128338) [ 2706.225180] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 16:32:28 (1760128348) [ 2715.552770] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 16:32:37 (1760128357) [ 2724.914666] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 16:32:47 (1760128367) [ 2735.524689] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 16:32:57 (1760128377) [ 2744.536868] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 16:33:06 (1760128386) [ 2754.397844] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 16:33:16 (1760128396) [ 2763.969211] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 16:33:26 (1760128406) [ 2773.527183] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 16:33:35 (1760128415) [ 3390.946758] Lustre: lustre-OST0000-osc-ffff9ec3d0e4d000: disconnect after 20s idle [ 3390.958083] Lustre: Skipped 10 previous similar messages [ 3640.374977] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 16:48:02 (1760129282) [ 3647.183689] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 16:48:09 (1760129289) [ 3654.383274] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 16:48:16 (1760129296) [ 3661.169526] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 16:48:23 (1760129303) [ 3667.648994] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 16:48:30 (1760129310) [ 3674.224113] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 16:48:36 (1760129316) [ 3681.244373] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 16:48:43 (1760129323) [ 3688.400886] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 16:48:50 (1760129330) [ 3694.795416] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 16:48:57 (1760129337) [ 3733.776506] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 16:49:36 (1760129376) [ 3740.383622] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 16:49:42 (1760129382) [ 3746.508796] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 16:49:49 (1760129389) [ 3752.459761] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 16:49:55 (1760129395) [ 3758.182313] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 16:50:00 (1760129400) [ 3763.849290] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 16:50:06 (1760129406) [ 3769.539865] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 16:50:12 (1760129412) [ 3775.398083] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 16:50:17 (1760129417) [ 3781.023189] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 16:50:23 (1760129423) [ 3836.163979] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 16:51:18 (1760129478) [ 4010.463347] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 22s idle [ 4010.465877] Lustre: Skipped 6 previous similar messages [ 4403.602745] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 17:00:46 (1760130046) [ 4409.769485] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 17:00:52 (1760130052) [ 4415.796402] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 17:00:58 (1760130058) [ 4421.712529] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 17:01:04 (1760130064) [ 4427.345085] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 17:01:09 (1760130069) [ 4433.283719] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 17:01:15 (1760130075) [ 4438.936880] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 17:01:21 (1760130081) [ 4444.678585] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 17:01:27 (1760130087) [ 4450.457406] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 17:01:33 (1760130093) [ 4456.156120] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 17:01:38 (1760130098) [ 4506.043965] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 17:02:28 (1760130148) [ 4511.809843] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 17:02:34 (1760130154) [ 4517.794871] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 17:02:40 (1760130160) [ 4523.401938] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 17:02:46 (1760130166) [ 4528.915282] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 17:02:51 (1760130171) [ 4534.359066] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 17:02:56 (1760130176) [ 4539.861371] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 17:03:02 (1760130182) [ 4544.598907] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 17:03:07 (1760130187) [ 4550.029666] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 17:03:12 (1760130192) [ 5022.007465] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 17:11:04 (1760130664) [ 5027.041638] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 17:11:09 (1760130669) [ 5032.224155] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 17:11:14 (1760130674) [ 5037.450974] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 17:11:20 (1760130680) [ 5042.828565] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 17:11:25 (1760130685) [ 5048.226107] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 17:11:30 (1760130690) [ 5053.572070] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 17:11:36 (1760130696) [ 5059.083677] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 17:11:41 (1760130701) [ 5064.585220] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 17:11:47 (1760130707) [ 5070.153847] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 17:11:52 (1760130712) [ 5075.244744] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 17:11:57 (1760130717) [ 5081.430382] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 17:12:04 (1760130724) [ 5086.631713] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 17:12:09 (1760130729) [ 5092.013245] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 17:12:14 (1760130734) [ 5097.135494] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 17:12:19 (1760130739) [ 5102.308229] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 17:12:24 (1760130744) [ 5108.312712] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 17:12:30 (1760130750) [ 5108.390852] LustreError: 6156:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5110.471162] LustreError: 6156:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5115.803084] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 17:12:38 (1760130758) [ 5120.117150] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 17:12:42 (1760130762) [ 5120.193820] LustreError: 284947:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5124.255109] LustreError: 284947:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5124.261089] LustreError: 284947:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5128.319119] LustreError: 284947:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5128.332695] LustreError: 284954:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5132.391159] LustreError: 284954:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5134.728088] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 17:12:57 (1760130777) [ 5141.471571] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 17:13:04 (1760130784) [ 5144.732057] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 17:13:07 (1760130787) [ 5149.071373] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 17:13:11 (1760130791) [ 5157.343200] Lustre: lustre-OST0000-osc-ffff9ec3c635f800: disconnect after 23s idle [ 5157.346131] Lustre: Skipped 4 previous similar messages [ 5173.371350] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 17:13:35 (1760130815) [ 5180.974780] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 17:13:43 (1760130823) [ 5188.588181] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 17:13:51 (1760130831) [ 5201.430835] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 17:14:04 (1760130844) [ 5210.800426] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 17:14:13 (1760130853) [ 5223.922734] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 17:14:26 (1760130866) [ 5236.859015] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 17:14:39 (1760130879) [ 5250.753574] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 17:14:53 (1760130893) [ 5258.632570] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 17:15:01 (1760130901) [ 5289.854334] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 17:15:32 (1760130932) [ 5292.441162] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5294.812030] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 17:15:37 (1760130937) [ 5298.478828] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 17:15:41 (1760130941) [ 5301.044968] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 17:15:43 (1760130943) [ 5303.287840] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 17:15:45 (1760130945) [ 5313.622630] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 17:15:56 (1760130956) [ 5328.216339] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 17:16:10 (1760130970) [ 5330.314740] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 17:16:12 (1760130972) [ 5332.662275] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 17:16:15 (1760130975) [ 5335.752824] LustreError: lustre-MDT0000-mdc-ffff9ec3d0e4d000: operation ldlm_enqueue to node 192.168.203.105@tcp failed: rc = -35 [ 5335.755512] LustreError: Skipped 1 previous similar message [ 5338.992724] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 17:16:21 (1760130981) [ 5339.142912] LustreError: 2409:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5341.223122] LustreError: 2409:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5346.309298] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 17:16:28 (1760130988) [ 5395.326581] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 17:17:17 (1760131037) [ 5398.391750] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 17:17:21 (1760131041) [ 5402.530541] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 17:17:25 (1760131045) [ 5407.741827] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 17:17:30 (1760131050) [ 5413.080725] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 17:17:35 (1760131055) [ 5421.400217] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 17:17:44 (1760131064) [ 5429.249608] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 17:17:51 (1760131071) [ 5432.653665] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 17:17:55 (1760131075) [ 5436.452441] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 17:17:59 (1760131079) [ 5443.753738] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 17:18:06 (1760131086) [ 5484.312524] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 17:18:46 (1760131126) [ 5597.947431] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 17:20:40 (1760131240) [ 5709.821560] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 17:22:32 (1760131352) [ 5761.504163] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: disconnect after 20s idle [ 5761.507257] Lustre: Skipped 9 previous similar messages [ 5982.660571] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 17:27:05 (1760131625) [ 6010.039141] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 17:27:32 (1760131652) [ 6038.977703] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 17:28:01 (1760131681) [ 6065.636582] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 17:28:28 (1760131708) [ 6126.403195] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 17:29:29 (1760131769) [ 6180.008938] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 17:30:22 (1760131822) [ 6269.557975] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 17:31:52 (1760131912) [ 6273.216982] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 17:31:55 (1760131915) [ 6324.321720] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 17:32:46 (1760131966) [ 6370.953821] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 17:33:33 (1760132013) [ 6374.799324] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 17:33:37 (1760132017) [ 6375.903238] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: disconnect after 24s idle [ 6375.905919] Lustre: Skipped 9 previous similar messages [ 6416.421986] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 17:34:19 (1760132059) [ 6428.665071] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 17:34:31 (1760132071) [ 6468.900926] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 17:35:11 (1760132111) [ 6472.129807] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 17:35:14 (1760132114) [ 6485.106362] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 17:35:27 (1760132127) [ 6489.109758] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 17:35:31 (1760132131) [ 6489.584147] LustreError: 347013:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x99f:0x0]: rc = -5 [ 6489.587278] LustreError: 347013:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6490.142791] LustreError: 347228:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xbd6:0x0]: rc = -5 [ 6490.147032] LustreError: 347228:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 8 previous similar messages [ 6490.149488] LustreError: 347228:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6490.152283] LustreError: 347228:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 8 previous similar messages [ 6491.202217] LustreError: 347312:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xbe9:0x0]: rc = -5 [ 6491.208179] LustreError: 347312:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 23 previous similar messages [ 6491.211248] LustreError: 347312:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6491.214630] LustreError: 347312:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 23 previous similar messages [ 6493.215436] LustreError: 347477:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0xa11:0x0]: rc = -5 [ 6493.219929] LustreError: 347477:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 49 previous similar messages [ 6493.223767] LustreError: 347477:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6493.226709] LustreError: 347477:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 49 previous similar messages [ 6495.759286] LustreError: 347699:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9ec3d0e4d000: inode [0x240000402:0xa45:0x0] mdc close failed: rc = -2 [ 6497.263391] LustreError: 347843:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0xa7b:0x0]: rc = -5 [ 6497.266984] LustreError: 347843:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 143 previous similar messages [ 6497.269560] LustreError: 347843:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6497.271917] LustreError: 347843:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 143 previous similar messages [ 6505.325707] LustreError: 348540:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xd90:0x0]: rc = -5 [ 6505.328405] LustreError: 348540:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 271 previous similar messages [ 6505.330338] LustreError: 348540:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6505.332563] LustreError: 348540:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 271 previous similar messages [ 6521.434699] LustreError: 349998:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0xd3f:0x0]: rc = -5 [ 6521.438611] LustreError: 349998:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 567 previous similar messages [ 6521.441466] LustreError: 349998:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6521.444199] LustreError: 349998:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 567 previous similar messages [ 6645.626606] LustreError: 347013:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0x11e6:0x0]: rc = -5 [ 6645.631728] LustreError: 347013:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 757 previous similar messages [ 6645.635558] LustreError: 347013:0:(llite_lib.c:3795:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6645.639346] LustreError: 347013:0:(llite_lib.c:3795:ll_prep_inode()) Skipped 757 previous similar messages [ 6645.641431] LustreError: lustre-MDT0000-mdc-ffff9ec3c635f800: operation mds_getattr_lock to node 192.168.203.105@tcp failed: rc = -107 [ 6645.646680] Lustre: lustre-MDT0000-mdc-ffff9ec3c635f800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6645.651655] LustreError: lustre-MDT0000-mdc-ffff9ec3c635f800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 6645.657781] LustreError: 351954:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9ec3c635f800: inode [0x200000402:0x11e2:0x0] mdc close failed: rc = -108 [ 6645.670364] LustreError: 351974:0:(file.c:6101:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 6645.680800] Lustre: lustre-MDT0000-mdc-ffff9ec3c635f800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 6648.176582] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 17:38:10 (1760132290) [ 6650.366236] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 17:38:12 (1760132292) [ 6699.085857] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 17:39:01 (1760132341) [ 6699.593635] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6700.149436] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 17:39:02 (1760132342) [ 6743.202327] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 17:39:45 (1760132385) [ 6745.411798] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 17:39:48 (1760132388) [ 6867.755528] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 17:41:50 (1760132510) [ 6875.419402] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 17:41:57 (1760132517) [ 6878.606256] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 17:42:01 (1760132521) [ 7060.869938] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 17:45:03 (1760132703) [ 7143.903120] Lustre: lustre-OST0001-osc-ffff9ec3c635f800: disconnect after 20s idle [ 7143.905620] Lustre: Skipped 3 previous similar messages [ 7243.129346] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 17:48:05 (1760132885) [ 7245.231656] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 17:48:07 (1760132887) [ 7254.152323] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 17:48:16 (1760132896) [ 7254.204421] Lustre: DEBUG MARKER: write [ 7254.220127] LustreError: 15053:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7256.224624] Lustre: DEBUG MARKER: kill 414364 [ 7256.226725] LustreError: 414364:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7259.319096] LustreError: 15053:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7262.263175] LustreError: 414364:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7264.453685] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 17:48:27 (1760132907) [ 7266.613584] LustreError: 414977:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7276.703155] LustreError: 414977:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7278.957720] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 17:48:41 (1760132921) [ 7279.055434] LustreError: 415564:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7281.135099] LustreError: 415564:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [ 7287.198226] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 17:48:49 (1760132929) [ 7287.748148] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7288.338701] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 17:48:50 (1760132930) [ 7291.027552] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 17:48:53 (1760132933) [ 7293.347244] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 17:48:55 (1760132935) [ 7295.633874] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 17:48:58 (1760132938) [ 7297.941652] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 17:49:00 (1760132940) [ 7300.065310] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 17:49:02 (1760132942) [ 7302.395205] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 17:49:05 (1760132945) [ 7305.561245] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 17:49:08 (1760132948) [ 7308.258691] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 17:49:10 (1760132950) [ 7308.904759] Lustre: *** cfs_fail_loc=415, val=0*** [ 7315.458945] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 17:49:18 (1760132958) [ 7334.545632] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 17:49:37 (1760132977) [ 7334.641266] LustreError: 15053:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7334.643605] LustreError: 15053:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7339.735084] LustreError: 15638:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7339.735084] LustreError: 15637:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7339.735097] LustreError: 15637:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 7339.738045] LustreError: 15638:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 7349.935107] LustreError: 15638:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7349.938037] LustreError: 15638:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 7352.195761] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 17:49:54 (1760132994) [ 7354.617728] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 17:49:57 (1760132997) [ 7357.064865] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 17:49:59 (1760132999) [ 7359.220114] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 17:50:01 (1760133001) [ 7363.465977] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 17:50:06 (1760133006) [ 7371.789992] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 17:50:14 (1760133014) [ 7371.938594] LustreError: 426295:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7371.940654] LustreError: 426295:0:(osc_request.c:2978:osc_build_rpc()) Skipped 6 previous similar messages [ 7375.999111] LustreError: 426295:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7376.001064] LustreError: 426295:0:(osc_request.c:2978:osc_build_rpc()) Skipped 1 previous similar message [ 7378.281641] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 17:50:20 (1760133020) [ 7379.451583] LustreError: 427004:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c635f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7379.458365] LustreError: 427004:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7379.479074] Lustre: Unmounted lustre-client [ 7380.310817] LustreError: 427024:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3d0e4d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7380.313541] LustreError: 427024:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7380.316509] LustreError: 427024:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7380.321031] LustreError: 427024:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7380.420709] Lustre: Unmounted lustre-client [ 7380.880296] Lustre: DEBUG MARKER: Iteration 1 [ 7381.038312] LustreError: 427187:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7381.039330] LustreError: 427188:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7381.043542] LustreError: 427187:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7381.080694] Lustre: Mounted lustre-client [ 7381.519299] LustreError: 427285:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3f6be7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7381.522427] LustreError: 427286:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7381.523242] LustreError: 427285:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 7381.526482] LustreError: 427286:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 7381.568080] Lustre: Unmounted lustre-client [ 7382.488432] Key type lgssc unregistered [ 7382.609411] LNet: 427528:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7382.611449] LNetError: 427528:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7382.618796] LNet: Removed LNI 192.168.203.5@tcp [ 7382.908129] Key type .llcrypt unregistered [ 7382.909810] Key type ._llcrypt unregistered [ 7383.130019] Key type ._llcrypt registered [ 7383.135315] Key type .llcrypt registered [ 7383.462175] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7383.466745] alg: No test for adler32 (adler32-zlib) [ 7384.419529] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7384.663939] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7386.263146] Key type lgssc registered [ 7386.760309] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7390.994135] Lustre: DEBUG MARKER: Iteration 2 [ 7391.094485] LustreError: 428357:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7391.094524] LustreError: 428356:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7391.098249] LustreError: 428357:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7392.154209] Lustre: Mounted lustre-client [ 7392.156205] Lustre: Skipped 1 previous similar message [ 7392.609669] LustreError: 428461:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3f04c2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7392.612881] LustreError: 428461:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7392.613866] LustreError: 428460:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7392.618899] LustreError: 428460:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7392.671070] Lustre: Unmounted lustre-client [ 7393.656195] Key type lgssc unregistered [ 7393.778409] LNet: 428703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7393.780373] LNetError: 428703:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7393.788658] LNet: Removed LNI 192.168.203.5@tcp [ 7394.035162] Key type .llcrypt unregistered [ 7394.036206] Key type ._llcrypt unregistered [ 7394.295774] Key type ._llcrypt registered [ 7394.296885] Key type .llcrypt registered [ 7394.475856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7394.481098] alg: No test for adler32 (adler32-zlib) [ 7395.339962] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7395.419587] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7396.999204] Key type lgssc registered [ 7397.385807] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7400.870546] Lustre: DEBUG MARKER: Iteration 3 [ 7400.970229] LustreError: 429532:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7400.970448] LustreError: 429533:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7400.973983] LustreError: 429532:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7402.021069] Lustre: Mounted lustre-client [ 7402.022930] Lustre: Skipped 1 previous similar message [ 7402.450985] LustreError: 429632:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e027a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7402.454161] LustreError: 429632:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7402.455111] LustreError: 429633:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7402.491135] Lustre: Unmounted lustre-client [ 7403.440771] Key type lgssc unregistered [ 7403.553322] LNet: 429875:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7403.555238] LNetError: 429875:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7403.563481] LNet: Removed LNI 192.168.203.5@tcp [ 7403.787123] Key type .llcrypt unregistered [ 7403.788777] Key type ._llcrypt unregistered [ 7404.015363] Key type ._llcrypt registered [ 7404.017281] Key type .llcrypt registered [ 7404.252596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7404.257716] alg: No test for adler32 (adler32-zlib) [ 7405.112568] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7405.194987] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7406.775139] Key type lgssc registered [ 7407.156712] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7410.557064] Lustre: DEBUG MARKER: Iteration 4 [ 7410.659446] LustreError: 430703:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7410.659491] LustreError: 430704:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7410.664141] LustreError: 430703:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7411.719546] Lustre: Mounted lustre-client [ 7412.194117] LustreError: 430808:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8cc4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7412.197273] LustreError: 430808:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7412.197767] LustreError: 430809:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7412.202476] LustreError: 430809:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7412.235077] Lustre: Unmounted lustre-client [ 7413.279460] Key type lgssc unregistered [ 7413.397430] LNet: 431050:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7413.401317] LNetError: 431050:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7413.411716] LNet: Removed LNI 192.168.203.5@tcp [ 7413.657096] Key type .llcrypt unregistered [ 7413.658851] Key type ._llcrypt unregistered [ 7413.911271] Key type ._llcrypt registered [ 7413.916147] Key type .llcrypt registered [ 7414.115453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7414.120205] alg: No test for adler32 (adler32-zlib) [ 7414.976635] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7415.062421] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7416.647156] Key type lgssc registered [ 7417.048157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7420.578157] Lustre: DEBUG MARKER: Iteration 5 [ 7420.699782] LustreError: 431879:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7420.700111] LustreError: 431880:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7420.703821] LustreError: 431879:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7421.765547] Lustre: Mounted lustre-client [ 7421.766597] Lustre: Skipped 1 previous similar message [ 7422.309756] LustreError: 431983:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8a43800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7422.316605] LustreError: 431983:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7422.319701] LustreError: 431983:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7422.380183] Lustre: Unmounted lustre-client [ 7423.396329] Key type lgssc unregistered [ 7423.521384] LNet: 432224:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7423.523836] LNetError: 432224:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7423.531838] LNet: Removed LNI 192.168.203.5@tcp [ 7423.775097] Key type .llcrypt unregistered [ 7423.776083] Key type ._llcrypt unregistered [ 7424.019288] Key type ._llcrypt registered [ 7424.020497] Key type .llcrypt registered [ 7424.205918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7424.216045] alg: No test for adler32 (adler32-zlib) [ 7425.070809] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7425.150269] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7426.735165] Key type lgssc registered [ 7427.179245] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7430.673878] Lustre: DEBUG MARKER: Iteration 6 [ 7430.780768] LustreError: 433053:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7430.781141] LustreError: 433054:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7430.787190] LustreError: 433053:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7431.837550] Lustre: Mounted lustre-client [ 7432.280409] LustreError: 433156:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c4de2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7432.282279] LustreError: 433155:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7432.284023] LustreError: 433156:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7432.315933] Lustre: Unmounted lustre-client [ 7433.351340] Key type lgssc unregistered [ 7433.480544] LNet: 433399:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7433.483389] LNetError: 433399:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7433.493281] LNet: Removed LNI 192.168.203.5@tcp [ 7433.761092] Key type .llcrypt unregistered [ 7433.762210] Key type ._llcrypt unregistered [ 7434.157452] Key type ._llcrypt registered [ 7434.159385] Key type .llcrypt registered [ 7434.268714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7434.274716] alg: No test for adler32 (adler32-zlib) [ 7435.126913] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7435.206877] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7436.791197] Key type lgssc registered [ 7437.223792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7440.973023] Lustre: DEBUG MARKER: Iteration 7 [ 7441.107723] LustreError: 434228:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7441.107800] LustreError: 434229:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7441.112762] LustreError: 434228:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7442.175776] Lustre: Mounted lustre-client [ 7442.176966] Lustre: Skipped 1 previous similar message [ 7442.703667] LustreError: 434331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c73a9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7442.708291] LustreError: 434331:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7442.766075] Lustre: Unmounted lustre-client [ 7443.783708] Key type lgssc unregistered [ 7443.904564] LNet: 434574:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7443.908297] LNetError: 434574:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7443.921382] LNet: Removed LNI 192.168.203.5@tcp [ 7444.188097] Key type .llcrypt unregistered [ 7444.189088] Key type ._llcrypt unregistered [ 7444.452358] Key type ._llcrypt registered [ 7444.453336] Key type .llcrypt registered [ 7444.658309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7444.664884] alg: No test for adler32 (adler32-zlib) [ 7445.556536] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7445.656066] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7447.247150] Key type lgssc registered [ 7447.622139] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7451.045279] Lustre: DEBUG MARKER: Iteration 8 [ 7451.149146] LustreError: 435403:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7451.150370] LustreError: 435402:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7451.153789] LustreError: 435403:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7452.203968] Lustre: Mounted lustre-client [ 7452.670204] LustreError: 435504:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e027c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7452.673359] LustreError: 435504:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7452.674439] LustreError: 435505:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7452.710765] Lustre: Unmounted lustre-client [ 7453.688854] Key type lgssc unregistered [ 7453.806471] LNet: 435746:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7453.810113] LNetError: 435746:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7453.818681] LNet: Removed LNI 192.168.203.5@tcp [ 7454.055098] Key type .llcrypt unregistered [ 7454.056075] Key type ._llcrypt unregistered [ 7454.306327] Key type ._llcrypt registered [ 7454.307318] Key type .llcrypt registered [ 7454.512170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7454.520490] alg: No test for adler32 (adler32-zlib) [ 7455.380489] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7455.469980] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7457.055140] Key type lgssc registered [ 7457.474362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7461.195355] Lustre: DEBUG MARKER: Iteration 9 [ 7461.312175] LustreError: 436575:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7461.313218] LustreError: 436576:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7461.319117] LustreError: 436575:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7462.382299] Lustre: Mounted lustre-client [ 7462.383431] Lustre: Skipped 1 previous similar message [ 7462.809282] LustreError: 436677:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c635b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7462.810892] LustreError: 436678:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7462.814986] LustreError: 436677:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7462.846366] Lustre: Unmounted lustre-client [ 7463.814235] Key type lgssc unregistered [ 7463.939385] LNet: 436920:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7463.941609] LNetError: 436920:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7463.948731] LNet: Removed LNI 192.168.203.5@tcp [ 7464.177131] Key type .llcrypt unregistered [ 7464.178253] Key type ._llcrypt unregistered [ 7464.448737] Key type ._llcrypt registered [ 7464.453070] Key type .llcrypt registered [ 7464.630889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7464.643063] alg: No test for adler32 (adler32-zlib) [ 7465.497546] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7465.580690] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7467.159176] Key type lgssc registered [ 7467.538839] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7471.175682] Lustre: DEBUG MARKER: Iteration 10 [ 7471.298753] LustreError: 437762:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7471.300078] LustreError: 437763:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7471.305486] LustreError: 437762:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7472.372859] Lustre: Mounted lustre-client [ 7472.374773] Lustre: Skipped 1 previous similar message [ 7472.928281] LustreError: 437866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fc391000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7472.932059] LustreError: 437866:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7472.932499] LustreError: 437867:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7472.936243] LustreError: 437867:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7472.986796] Lustre: Unmounted lustre-client [ 7474.095761] Key type lgssc unregistered [ 7474.220533] LNet: 438108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7474.222559] LNetError: 438108:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7474.230714] LNet: Removed LNI 192.168.203.5@tcp [ 7474.489084] Key type .llcrypt unregistered [ 7474.490229] Key type ._llcrypt unregistered [ 7474.727216] Key type ._llcrypt registered [ 7474.728222] Key type .llcrypt registered [ 7474.914457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7474.923577] alg: No test for adler32 (adler32-zlib) [ 7475.780399] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7475.869709] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7477.447121] Key type lgssc registered [ 7477.847951] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7481.456203] Lustre: DEBUG MARKER: Iteration 11 [ 7481.556562] LustreError: 438936:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7481.558337] LustreError: 438937:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7481.561770] LustreError: 438936:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7482.614380] Lustre: Mounted lustre-client [ 7482.615434] Lustre: Skipped 1 previous similar message [ 7483.054499] LustreError: 439040:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fc393000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7483.057784] LustreError: 439041:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7483.058568] LustreError: 439040:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7483.063281] LustreError: 439041:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7483.104895] Lustre: Unmounted lustre-client [ 7484.163212] Key type lgssc unregistered [ 7484.285783] LNet: 439283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7484.288739] LNetError: 439283:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7484.297250] LNet: Removed LNI 192.168.203.5@tcp [ 7484.565090] Key type .llcrypt unregistered [ 7484.566283] Key type ._llcrypt unregistered [ 7484.866257] Key type ._llcrypt registered [ 7484.867616] Key type .llcrypt registered [ 7485.042647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7485.049331] alg: No test for adler32 (adler32-zlib) [ 7485.908712] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7485.994460] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7487.575192] Key type lgssc registered [ 7488.030987] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7491.770366] Lustre: DEBUG MARKER: Iteration 12 [ 7491.909504] LustreError: 440113:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7491.910086] LustreError: 440112:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7491.915931] LustreError: 440113:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7492.979171] Lustre: Mounted lustre-client [ 7492.980349] Lustre: Skipped 1 previous similar message [ 7493.469715] LustreError: 440215:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c7b60000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7493.471475] LustreError: 440216:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7493.473721] LustreError: 440215:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7493.522313] Lustre: Unmounted lustre-client [ 7494.557405] Key type lgssc unregistered [ 7494.680488] LNet: 440459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7494.682835] LNetError: 440459:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7494.691834] LNet: Removed LNI 192.168.203.5@tcp [ 7494.920095] Key type .llcrypt unregistered [ 7494.921482] Key type ._llcrypt unregistered [ 7495.133031] Key type ._llcrypt registered [ 7495.133887] Key type .llcrypt registered [ 7495.329743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7495.339345] alg: No test for adler32 (adler32-zlib) [ 7496.204940] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7496.286179] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7497.871241] Key type lgssc registered [ 7498.274588] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7501.909112] Lustre: DEBUG MARKER: Iteration 13 [ 7502.024875] LustreError: 441288:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7502.024922] LustreError: 441289:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7502.030728] LustreError: 441288:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7503.095755] Lustre: Mounted lustre-client [ 7503.096883] Lustre: Skipped 1 previous similar message [ 7503.560948] LustreError: 441391:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c3b92800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7503.563880] LustreError: 441392:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7503.567300] LustreError: 441391:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7503.618067] Lustre: Unmounted lustre-client [ 7504.622818] Key type lgssc unregistered [ 7504.757443] LNet: 441634:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7504.759556] LNetError: 441634:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7504.767668] LNet: Removed LNI 192.168.203.5@tcp [ 7505.013102] Key type .llcrypt unregistered [ 7505.014179] Key type ._llcrypt unregistered [ 7505.249376] Key type ._llcrypt registered [ 7505.250526] Key type .llcrypt registered [ 7505.437777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7505.443736] alg: No test for adler32 (adler32-zlib) [ 7506.295868] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7506.376083] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7507.959185] Key type lgssc registered [ 7508.343294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7511.850899] Lustre: DEBUG MARKER: Iteration 14 [ 7511.963670] LustreError: 442462:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7511.963726] LustreError: 442463:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7511.969201] LustreError: 442462:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7513.026208] Lustre: Mounted lustre-client [ 7513.480626] LustreError: 442566:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e044b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7513.484190] LustreError: 442566:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7513.484712] LustreError: 442565:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7513.489257] LustreError: 442565:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7513.522780] Lustre: Unmounted lustre-client [ 7514.442461] Key type lgssc unregistered [ 7514.553329] LNet: 442807:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7514.555505] LNetError: 442807:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7514.566665] LNet: Removed LNI 192.168.203.5@tcp [ 7514.794089] Key type .llcrypt unregistered [ 7514.795360] Key type ._llcrypt unregistered [ 7515.102168] Key type ._llcrypt registered [ 7515.121040] Key type .llcrypt registered [ 7515.271426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7515.278085] alg: No test for adler32 (adler32-zlib) [ 7516.130852] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7516.207336] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7517.783225] Key type lgssc registered [ 7518.158593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7521.637643] Lustre: DEBUG MARKER: Iteration 15 [ 7521.739390] LustreError: 443636:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7521.739640] LustreError: 443637:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7521.744969] LustreError: 443636:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7522.802189] Lustre: Mounted lustre-client [ 7523.345416] LustreError: 443739:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8757800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7523.348874] LustreError: 443740:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7523.349232] LustreError: 443739:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7523.379076] Lustre: Unmounted lustre-client [ 7524.467932] Key type lgssc unregistered [ 7524.593655] LNet: 443981:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7524.596106] LNetError: 443981:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7524.604275] LNet: Removed LNI 192.168.203.5@tcp [ 7524.833118] Key type .llcrypt unregistered [ 7524.834152] Key type ._llcrypt unregistered [ 7525.064296] Key type ._llcrypt registered [ 7525.065192] Key type .llcrypt registered [ 7525.252312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7525.259859] alg: No test for adler32 (adler32-zlib) [ 7526.113320] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7526.191933] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7527.775196] Key type lgssc registered [ 7528.164546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7531.994671] Lustre: DEBUG MARKER: Iteration 16 [ 7532.108266] LustreError: 444808:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7532.108314] LustreError: 444809:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7532.113446] LustreError: 444808:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7533.172821] Lustre: Mounted lustre-client [ 7533.749081] LustreError: 444912:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fc391000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7533.751330] LustreError: 444911:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7533.751910] LustreError: 444912:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7533.793099] Lustre: Unmounted lustre-client [ 7534.856356] Key type lgssc unregistered [ 7534.984467] LNet: 445155:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7534.986803] LNetError: 445155:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7534.994721] LNet: Removed LNI 192.168.203.5@tcp [ 7535.219121] Key type .llcrypt unregistered [ 7535.221044] Key type ._llcrypt unregistered [ 7535.538592] Key type ._llcrypt registered [ 7535.540319] Key type .llcrypt registered [ 7535.780049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7535.788244] alg: No test for adler32 (adler32-zlib) [ 7536.645488] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7536.730620] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7538.311104] Key type lgssc registered [ 7538.688579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7542.323240] Lustre: DEBUG MARKER: Iteration 17 [ 7542.459529] LustreError: 445984:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7542.459932] LustreError: 445983:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7542.463290] LustreError: 445984:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7543.522674] Lustre: Mounted lustre-client [ 7544.043737] LustreError: 446084:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e044b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7544.046442] LustreError: 446083:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7544.047980] LustreError: 446084:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7544.093347] Lustre: Unmounted lustre-client [ 7545.217620] Key type lgssc unregistered [ 7545.356597] LNet: 446326:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7545.358685] LNetError: 446326:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7545.366967] LNet: Removed LNI 192.168.203.5@tcp [ 7545.652101] Key type .llcrypt unregistered [ 7545.653038] Key type ._llcrypt unregistered [ 7545.982145] Key type ._llcrypt registered [ 7545.983127] Key type .llcrypt registered [ 7546.224319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7546.230454] alg: No test for adler32 (adler32-zlib) [ 7547.104996] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7547.210689] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7548.807206] Key type lgssc registered [ 7549.246194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7552.839948] Lustre: DEBUG MARKER: Iteration 18 [ 7552.971952] LustreError: 447155:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7552.972051] LustreError: 447154:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7552.977403] LustreError: 447155:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7554.041151] Lustre: Mounted lustre-client [ 7554.042791] Lustre: Skipped 1 previous similar message [ 7554.488630] LustreError: 447259:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8cc1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7554.492393] LustreError: 447258:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7554.492831] LustreError: 447259:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7554.546085] Lustre: Unmounted lustre-client [ 7555.623138] Key type lgssc unregistered [ 7555.742501] LNet: 447502:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7555.744834] LNetError: 447502:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7555.752194] LNet: Removed LNI 192.168.203.5@tcp [ 7556.020118] Key type .llcrypt unregistered [ 7556.021099] Key type ._llcrypt unregistered [ 7556.273055] Key type ._llcrypt registered [ 7556.274102] Key type .llcrypt registered [ 7556.510502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7556.527274] alg: No test for adler32 (adler32-zlib) [ 7557.395181] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7557.490969] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7559.079141] Key type lgssc registered [ 7559.484427] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7563.206695] Lustre: DEBUG MARKER: Iteration 19 [ 7563.315134] LustreError: 448329:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7563.315184] LustreError: 448330:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7563.320481] LustreError: 448329:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7564.378624] Lustre: Mounted lustre-client [ 7564.379747] Lustre: Skipped 1 previous similar message [ 7564.840086] LustreError: 448434:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3faa0b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7564.841968] LustreError: 448435:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7564.844253] LustreError: 448434:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7564.899140] Lustre: Unmounted lustre-client [ 7565.995069] Key type lgssc unregistered [ 7566.119544] LNet: 448677:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7566.121813] LNetError: 448677:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7566.130078] LNet: Removed LNI 192.168.203.5@tcp [ 7566.425150] Key type .llcrypt unregistered [ 7566.426217] Key type ._llcrypt unregistered [ 7566.658074] Key type ._llcrypt registered [ 7566.659229] Key type .llcrypt registered [ 7566.820099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7566.867122] alg: No test for adler32 (adler32-zlib) [ 7567.754348] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7567.846578] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7569.439160] Key type lgssc registered [ 7569.872542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7574.131888] Lustre: DEBUG MARKER: Iteration 20 [ 7574.264490] LustreError: 449505:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7574.264828] LustreError: 449506:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7574.268683] LustreError: 449505:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7575.334767] Lustre: Mounted lustre-client [ 7575.898864] LustreError: 449607:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3f945e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7575.902170] LustreError: 449608:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7575.902973] LustreError: 449607:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7575.944221] Lustre: Unmounted lustre-client [ 7577.064635] Key type lgssc unregistered [ 7577.192748] LNet: 449849:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7577.194984] LNetError: 449849:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7577.202600] LNet: Removed LNI 192.168.203.5@tcp [ 7577.470099] Key type .llcrypt unregistered [ 7577.471548] Key type ._llcrypt unregistered [ 7577.743400] Key type ._llcrypt registered [ 7577.744940] Key type .llcrypt registered [ 7577.936829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7577.942032] alg: No test for adler32 (adler32-zlib) [ 7578.792521] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7578.877273] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7580.463147] Key type lgssc registered [ 7580.840900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7584.609131] Lustre: DEBUG MARKER: Iteration 21 [ 7584.727769] LustreError: 450677:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7584.730125] LustreError: 450678:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7584.733103] LustreError: 450677:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7585.810022] Lustre: Mounted lustre-client [ 7585.810961] Lustre: Skipped 1 previous similar message [ 7586.267927] LustreError: 450781:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8753000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7586.271208] LustreError: 450782:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7586.271856] LustreError: 450781:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7586.309944] Lustre: Unmounted lustre-client [ 7587.331052] Key type lgssc unregistered [ 7587.450536] LNet: 451024:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7587.452795] LNetError: 451024:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7587.459717] LNet: Removed LNI 192.168.203.5@tcp [ 7587.713085] Key type .llcrypt unregistered [ 7587.714604] Key type ._llcrypt unregistered [ 7587.976902] Key type ._llcrypt registered [ 7587.978702] Key type .llcrypt registered [ 7588.163437] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7588.168485] alg: No test for adler32 (adler32-zlib) [ 7589.020330] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7589.109917] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7590.695148] Key type lgssc registered [ 7591.078964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7594.749816] Lustre: DEBUG MARKER: Iteration 22 [ 7594.851282] LustreError: 451853:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7594.851308] LustreError: 451854:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7594.858370] LustreError: 451853:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7595.913541] Lustre: Mounted lustre-client [ 7595.914748] Lustre: Skipped 1 previous similar message [ 7596.410381] LustreError: 451953:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8a43800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7596.414523] LustreError: 451954:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7596.415306] LustreError: 451953:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7596.464510] Lustre: Unmounted lustre-client [ 7597.522420] Key type lgssc unregistered [ 7597.641525] LNet: 452195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7597.644098] LNetError: 452195:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7597.652725] LNet: Removed LNI 192.168.203.5@tcp [ 7597.898103] Key type .llcrypt unregistered [ 7597.899079] Key type ._llcrypt unregistered [ 7598.229131] Key type ._llcrypt registered [ 7598.234649] Key type .llcrypt registered [ 7598.458828] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7598.465663] alg: No test for adler32 (adler32-zlib) [ 7599.346682] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7599.438118] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7601.031117] Key type lgssc registered [ 7601.436151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7605.381494] Lustre: DEBUG MARKER: Iteration 23 [ 7605.515353] LustreError: 453024:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7605.515465] LustreError: 453025:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7605.519251] LustreError: 453024:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7606.588036] Lustre: Mounted lustre-client [ 7606.589300] Lustre: Skipped 1 previous similar message [ 7607.119991] LustreError: 453127:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8a47800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7607.123149] LustreError: 453128:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7607.124901] LustreError: 453127:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7607.179465] Lustre: Unmounted lustre-client [ 7608.240617] Key type lgssc unregistered [ 7608.357439] LNet: 453369:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.361349] LNetError: 453369:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7608.368666] LNet: Removed LNI 192.168.203.5@tcp [ 7608.605143] Key type .llcrypt unregistered [ 7608.606510] Key type ._llcrypt unregistered [ 7608.914309] Key type ._llcrypt registered [ 7608.939198] Key type .llcrypt registered [ 7609.067664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7609.074285] alg: No test for adler32 (adler32-zlib) [ 7609.952842] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7610.046534] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7611.639122] Key type lgssc registered [ 7612.029129] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7615.547604] Lustre: DEBUG MARKER: Iteration 24 [ 7615.646364] LustreError: 454197:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7615.647710] LustreError: 454199:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7615.651152] LustreError: 454197:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7616.704660] Lustre: Mounted lustre-client [ 7616.705768] Lustre: Skipped 1 previous similar message [ 7617.188630] LustreError: 454298:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c9677000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7617.191381] LustreError: 454299:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7617.191748] LustreError: 454298:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7617.226294] Lustre: Unmounted lustre-client [ 7618.275713] Key type lgssc unregistered [ 7618.402471] LNet: 454540:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7618.404698] LNetError: 454540:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7618.414736] LNet: Removed LNI 192.168.203.5@tcp [ 7618.664111] Key type .llcrypt unregistered [ 7618.665706] Key type ._llcrypt unregistered [ 7618.928952] Key type ._llcrypt registered [ 7618.930276] Key type .llcrypt registered [ 7619.113535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7619.121483] alg: No test for adler32 (adler32-zlib) [ 7619.979555] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7620.065162] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7621.647097] Key type lgssc registered [ 7622.049514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7625.656130] Lustre: DEBUG MARKER: Iteration 25 [ 7625.758525] LustreError: 455370:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7625.758582] LustreError: 455372:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7625.762855] LustreError: 455370:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7626.813582] Lustre: Mounted lustre-client [ 7626.815214] Lustre: Skipped 1 previous similar message [ 7627.285028] LustreError: 455476:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c3b97800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7627.287338] LustreError: 455475:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7627.288212] LustreError: 455476:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7627.328520] Lustre: Unmounted lustre-client [ 7627.330080] Lustre: Skipped 1 previous similar message [ 7628.390288] Key type lgssc unregistered [ 7628.514537] LNet: 455718:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7628.517312] LNetError: 455718:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7628.527809] LNet: Removed LNI 192.168.203.5@tcp [ 7628.783115] Key type .llcrypt unregistered [ 7628.784849] Key type ._llcrypt unregistered [ 7629.029511] Key type ._llcrypt registered [ 7629.031340] Key type .llcrypt registered [ 7629.221527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7629.226370] alg: No test for adler32 (adler32-zlib) [ 7630.098299] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7630.178380] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7631.759116] Key type lgssc registered [ 7632.164835] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7635.742650] Lustre: DEBUG MARKER: Iteration 26 [ 7635.873319] LustreError: 456545:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7635.880610] LustreError: 456548:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7635.883934] LustreError: 456545:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 7636.954440] Lustre: Mounted lustre-client [ 7636.956201] Lustre: Skipped 1 previous similar message [ 7637.573816] LustreError: 456649:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c73ad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7637.581895] LustreError: 456649:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7637.584992] LustreError: 456649:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7637.639079] Lustre: Unmounted lustre-client [ 7638.769851] Key type lgssc unregistered [ 7638.910545] LNet: 456892:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7638.912626] LNetError: 456892:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7638.920286] LNet: Removed LNI 192.168.203.5@tcp [ 7639.208116] Key type .llcrypt unregistered [ 7639.209553] Key type ._llcrypt unregistered [ 7639.498551] Key type ._llcrypt registered [ 7639.500226] Key type .llcrypt registered [ 7639.742958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7639.765664] alg: No test for adler32 (adler32-zlib) [ 7640.649447] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7640.746508] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7642.351176] Key type lgssc registered [ 7642.739513] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7646.746704] Lustre: DEBUG MARKER: Iteration 27 [ 7646.876577] LustreError: 457723:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7646.876639] LustreError: 457724:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7646.883170] LustreError: 457723:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7647.956161] Lustre: Mounted lustre-client [ 7647.957200] Lustre: Skipped 1 previous similar message [ 7648.509382] LustreError: 457826:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3ff7bf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7648.513069] LustreError: 457827:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7648.513548] LustreError: 457826:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7648.535857] Lustre: Unmounted lustre-client [ 7649.609815] Key type lgssc unregistered [ 7649.720306] LNet: 458069:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7649.722464] LNetError: 458069:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7649.731882] LNet: Removed LNI 192.168.203.5@tcp [ 7649.985135] Key type .llcrypt unregistered [ 7649.986555] Key type ._llcrypt unregistered [ 7650.212922] Key type ._llcrypt registered [ 7650.219729] Key type .llcrypt registered [ 7650.438240] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7650.445447] alg: No test for adler32 (adler32-zlib) [ 7651.326823] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7651.432818] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7653.023104] Key type lgssc registered [ 7653.438118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7657.223472] Lustre: DEBUG MARKER: Iteration 28 [ 7657.342894] LustreError: 458898:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7657.344920] LustreError: 458899:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7657.350720] LustreError: 458898:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7658.419739] Lustre: Mounted lustre-client [ 7658.965260] LustreError: 459001:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e8c09800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7658.970758] LustreError: 459001:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7659.026899] Lustre: Unmounted lustre-client [ 7660.119276] Key type lgssc unregistered [ 7660.254810] LNet: 459243:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7660.257485] LNetError: 459243:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7660.268287] LNet: Removed LNI 192.168.203.5@tcp [ 7660.547182] Key type .llcrypt unregistered [ 7660.548266] Key type ._llcrypt unregistered [ 7660.870535] Key type ._llcrypt registered [ 7660.871575] Key type .llcrypt registered [ 7661.100401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7661.106103] alg: No test for adler32 (adler32-zlib) [ 7661.982654] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7662.085171] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7663.679154] Key type lgssc registered [ 7664.055110] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7667.733285] Lustre: DEBUG MARKER: Iteration 29 [ 7667.838418] LustreError: 460071:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7667.838470] LustreError: 460072:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7667.842716] LustreError: 460071:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7668.897425] Lustre: Mounted lustre-client [ 7668.899040] Lustre: Skipped 1 previous similar message [ 7669.449110] LustreError: 460176:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c9676800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7669.451279] LustreError: 460175:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7669.452294] LustreError: 460176:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7669.497115] Lustre: Unmounted lustre-client [ 7670.614879] Key type lgssc unregistered [ 7670.744826] LNet: 460419:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7670.747175] LNetError: 460419:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7670.754649] LNet: Removed LNI 192.168.203.5@tcp [ 7671.011092] Key type .llcrypt unregistered [ 7671.012126] Key type ._llcrypt unregistered [ 7671.306972] Key type ._llcrypt registered [ 7671.308172] Key type .llcrypt registered [ 7671.549522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7671.554528] alg: No test for adler32 (adler32-zlib) [ 7672.426259] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7672.521767] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7674.111127] Key type lgssc registered [ 7674.589169] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7678.272901] Lustre: DEBUG MARKER: Iteration 30 [ 7678.368107] LustreError: 461247:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7678.368178] LustreError: 461248:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7678.372393] LustreError: 461247:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7679.427979] Lustre: Mounted lustre-client [ 7679.428926] Lustre: Skipped 1 previous similar message [ 7679.941336] LustreError: 461345:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e0448800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7679.946941] LustreError: 461345:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7680.030082] Lustre: Unmounted lustre-client [ 7681.161325] Key type lgssc unregistered [ 7681.305559] LNet: 461593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7681.307870] LNetError: 461593:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7681.317478] LNet: Removed LNI 192.168.203.5@tcp [ 7681.583129] Key type .llcrypt unregistered [ 7681.584809] Key type ._llcrypt unregistered [ 7681.975521] Key type ._llcrypt registered [ 7681.977494] Key type .llcrypt registered [ 7682.164335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7682.169246] alg: No test for adler32 (adler32-zlib) [ 7683.042141] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7683.149197] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7684.743170] Key type lgssc registered [ 7685.182157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7688.767518] Lustre: DEBUG MARKER: Iteration 31 [ 7688.880294] LustreError: 462422:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7688.880326] LustreError: 462423:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7688.884547] LustreError: 462422:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7689.939132] Lustre: Mounted lustre-client [ 7690.510715] LustreError: 462524:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c6359800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7690.513697] LustreError: 462523:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7690.514948] LustreError: 462524:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7690.558962] Lustre: Unmounted lustre-client [ 7691.552537] Key type lgssc unregistered [ 7691.672495] LNet: 462766:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7691.675145] LNetError: 462766:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7691.685650] LNet: Removed LNI 192.168.203.5@tcp [ 7691.916107] Key type .llcrypt unregistered [ 7691.917093] Key type ._llcrypt unregistered [ 7692.172935] Key type ._llcrypt registered [ 7692.181428] Key type .llcrypt registered [ 7692.376687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7692.381403] alg: No test for adler32 (adler32-zlib) [ 7693.235618] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7693.315737] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7694.895173] Key type lgssc registered [ 7695.282782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7699.148106] Lustre: DEBUG MARKER: Iteration 32 [ 7699.271522] LustreError: 463594:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7699.271917] LustreError: 463595:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7699.276199] LustreError: 463594:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7700.327187] Lustre: Mounted lustre-client [ 7700.774099] LustreError: 463694:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8cc2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7700.776945] LustreError: 463694:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7700.778495] LustreError: 463695:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7700.780946] LustreError: 463695:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7700.816088] Lustre: Unmounted lustre-client [ 7701.821635] Key type lgssc unregistered [ 7701.949444] LNet: 463937:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7701.951860] LNetError: 463937:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7701.959722] LNet: Removed LNI 192.168.203.5@tcp [ 7702.219111] Key type .llcrypt unregistered [ 7702.220246] Key type ._llcrypt unregistered [ 7702.444854] Key type ._llcrypt registered [ 7702.445881] Key type .llcrypt registered [ 7702.670521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7702.675666] alg: No test for adler32 (adler32-zlib) [ 7703.540282] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7703.621495] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7705.207217] Key type lgssc registered [ 7705.629821] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7709.318357] Lustre: DEBUG MARKER: Iteration 33 [ 7709.429136] LustreError: 464765:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7709.429489] LustreError: 464766:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7709.435054] LustreError: 464765:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7710.492288] Lustre: Mounted lustre-client [ 7710.493277] Lustre: Skipped 1 previous similar message [ 7710.934560] LustreError: 464871:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fd67b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7710.936361] LustreError: 464870:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7710.937454] LustreError: 464871:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7710.984531] Lustre: Unmounted lustre-client [ 7711.998205] Key type lgssc unregistered [ 7712.138714] LNet: 465113:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7712.142220] LNetError: 465113:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7712.152117] LNet: Removed LNI 192.168.203.5@tcp [ 7712.418143] Key type .llcrypt unregistered [ 7712.420361] Key type ._llcrypt unregistered [ 7712.784054] Key type ._llcrypt registered [ 7712.785120] Key type .llcrypt registered [ 7712.942422] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7712.946871] alg: No test for adler32 (adler32-zlib) [ 7713.807529] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7713.892616] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7715.479118] Key type lgssc registered [ 7715.878294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7719.574161] Lustre: DEBUG MARKER: Iteration 34 [ 7719.679324] LustreError: 465943:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7719.680279] LustreError: 465944:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7719.686208] LustreError: 465943:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7720.737295] Lustre: Mounted lustre-client [ 7720.739035] Lustre: Skipped 1 previous similar message [ 7721.169842] LustreError: 466047:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8a42800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7721.171740] LustreError: 466046:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7721.175861] LustreError: 466047:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7721.209856] Lustre: Unmounted lustre-client [ 7722.190122] Key type lgssc unregistered [ 7722.327516] LNet: 466289:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7722.329658] LNetError: 466289:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7722.338712] LNet: Removed LNI 192.168.203.5@tcp [ 7722.586083] Key type .llcrypt unregistered [ 7722.587087] Key type ._llcrypt unregistered [ 7722.879304] Key type ._llcrypt registered [ 7722.880246] Key type .llcrypt registered [ 7723.036854] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7723.041232] alg: No test for adler32 (adler32-zlib) [ 7723.899819] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7723.978465] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7725.567130] Key type lgssc registered [ 7725.933687] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7729.342551] Lustre: DEBUG MARKER: Iteration 35 [ 7729.444432] LustreError: 467117:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7729.444512] LustreError: 467118:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7729.448946] LustreError: 467117:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7730.499544] Lustre: Mounted lustre-client [ 7730.940418] LustreError: 467220:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fbd96000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7730.946971] LustreError: 467220:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7730.997188] Lustre: Unmounted lustre-client [ 7730.998090] Lustre: Skipped 1 previous similar message [ 7732.055680] Key type lgssc unregistered [ 7732.178581] LNet: 467463:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7732.181401] LNetError: 467463:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7732.188787] LNet: Removed LNI 192.168.203.5@tcp [ 7732.442107] Key type .llcrypt unregistered [ 7732.443420] Key type ._llcrypt unregistered [ 7732.715234] Key type ._llcrypt registered [ 7732.716241] Key type .llcrypt registered [ 7732.897201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7732.902044] alg: No test for adler32 (adler32-zlib) [ 7733.758875] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7733.838860] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7735.423140] Key type lgssc registered [ 7735.808787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7739.357241] Lustre: DEBUG MARKER: Iteration 36 [ 7739.460637] LustreError: 468292:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7739.460686] LustreError: 468293:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7739.465185] LustreError: 468292:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7740.517600] Lustre: Mounted lustre-client [ 7740.947191] LustreError: 468394:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c8cc5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7740.949911] LustreError: 468394:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7740.952487] LustreError: 468395:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7741.001993] Lustre: Unmounted lustre-client [ 7741.986866] Key type lgssc unregistered [ 7742.095478] LNet: 468636:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7742.098223] LNetError: 468636:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7742.106183] LNet: Removed LNI 192.168.203.5@tcp [ 7742.390074] Key type .llcrypt unregistered [ 7742.391108] Key type ._llcrypt unregistered [ 7742.648573] Key type ._llcrypt registered [ 7742.649578] Key type .llcrypt registered [ 7742.825933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7742.833716] alg: No test for adler32 (adler32-zlib) [ 7743.702481] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7743.788781] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7745.375169] Key type lgssc registered [ 7745.748211] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7749.601637] Lustre: DEBUG MARKER: Iteration 37 [ 7749.734182] LustreError: 469465:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7749.735792] LustreError: 469466:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7749.740838] LustreError: 469465:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7750.806991] Lustre: Mounted lustre-client [ 7750.807922] Lustre: Skipped 1 previous similar message [ 7751.357165] LustreError: 469567:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c3fda000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7751.359254] LustreError: 469568:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7751.359762] LustreError: 469567:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7751.402905] Lustre: Unmounted lustre-client [ 7751.404054] Lustre: Skipped 1 previous similar message [ 7752.665457] Key type lgssc unregistered [ 7752.784422] LNet: 469811:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7752.787141] LNetError: 469811:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7752.796429] LNet: Removed LNI 192.168.203.5@tcp [ 7753.051095] Key type .llcrypt unregistered [ 7753.052345] Key type ._llcrypt unregistered [ 7753.394722] Key type ._llcrypt registered [ 7753.404086] Key type .llcrypt registered [ 7753.597445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7753.602579] alg: No test for adler32 (adler32-zlib) [ 7754.483113] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7754.581950] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7756.167222] Key type lgssc registered [ 7756.529644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7759.893696] Lustre: DEBUG MARKER: Iteration 38 [ 7760.011237] LustreError: 470640:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7760.011534] LustreError: 470639:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7760.016232] LustreError: 470640:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7761.076411] Lustre: Mounted lustre-client [ 7761.590109] LustreError: 470743:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3ff5fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7761.594475] LustreError: 470743:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7761.632134] Lustre: Unmounted lustre-client [ 7762.670444] Key type lgssc unregistered [ 7762.810449] LNet: 470987:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7762.813710] LNetError: 470987:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7762.825993] LNet: Removed LNI 192.168.203.5@tcp [ 7763.045103] Key type .llcrypt unregistered [ 7763.046071] Key type ._llcrypt unregistered [ 7763.267479] Key type ._llcrypt registered [ 7763.268561] Key type .llcrypt registered [ 7763.462778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7763.476947] alg: No test for adler32 (adler32-zlib) [ 7764.329020] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7764.408890] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7765.983108] Key type lgssc registered [ 7766.359502] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7769.803466] Lustre: DEBUG MARKER: Iteration 39 [ 7769.931660] LustreError: 471814:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7769.931971] LustreError: 471815:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7769.937643] LustreError: 471814:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7770.994840] Lustre: Mounted lustre-client [ 7771.541586] LustreError: 471917:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3f04c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7771.544769] LustreError: 471917:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7771.545117] LustreError: 471916:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7771.548137] LustreError: 471916:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7771.621523] Lustre: Unmounted lustre-client [ 7772.680972] Key type lgssc unregistered [ 7772.816570] LNet: 472159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7772.820400] LNetError: 472159:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7772.828749] LNet: Removed LNI 192.168.203.5@tcp [ 7773.103108] Key type .llcrypt unregistered [ 7773.104068] Key type ._llcrypt unregistered [ 7773.386750] Key type ._llcrypt registered [ 7773.387653] Key type .llcrypt registered [ 7773.599931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7773.611369] alg: No test for adler32 (adler32-zlib) [ 7774.473337] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7774.558645] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7776.159243] Key type lgssc registered [ 7776.581254] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7780.033357] Lustre: DEBUG MARKER: Iteration 40 [ 7780.127683] LustreError: 472987:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7780.127984] LustreError: 472988:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7780.132758] LustreError: 472987:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7781.182346] Lustre: Mounted lustre-client [ 7781.626526] LustreError: 473089:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c4de0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7781.628445] LustreError: 473090:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7781.629657] LustreError: 473089:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7781.680057] Lustre: Unmounted lustre-client [ 7782.616700] Key type lgssc unregistered [ 7782.740483] LNet: 473332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7782.744807] LNetError: 473332:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7782.753809] LNet: Removed LNI 192.168.203.5@tcp [ 7782.994104] Key type .llcrypt unregistered [ 7782.995166] Key type ._llcrypt unregistered [ 7783.259907] Key type ._llcrypt registered [ 7783.262087] Key type .llcrypt registered [ 7783.431420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7783.436052] alg: No test for adler32 (adler32-zlib) [ 7784.283756] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7784.371706] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7785.951171] Key type lgssc registered [ 7786.298062] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7789.587086] Lustre: DEBUG MARKER: Iteration 41 [ 7789.694355] LustreError: 474160:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7789.694380] LustreError: 474161:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7789.698499] LustreError: 474160:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7790.750335] Lustre: Mounted lustre-client [ 7790.751571] Lustre: Skipped 1 previous similar message [ 7791.196994] LustreError: 474263:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c7213000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7791.199253] LustreError: 474264:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7791.200648] LustreError: 474263:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7791.232781] Lustre: Unmounted lustre-client [ 7792.167698] Key type lgssc unregistered [ 7792.307521] LNet: 474506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7792.311884] LNetError: 474506:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7792.319774] LNet: Removed LNI 192.168.203.5@tcp [ 7792.553101] Key type .llcrypt unregistered [ 7792.554093] Key type ._llcrypt unregistered [ 7792.886190] Key type ._llcrypt registered [ 7792.894881] Key type .llcrypt registered [ 7793.097784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7793.102748] alg: No test for adler32 (adler32-zlib) [ 7793.948660] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7794.031507] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7795.607099] Key type lgssc registered [ 7795.950079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7799.520403] Lustre: DEBUG MARKER: Iteration 42 [ 7799.648329] LustreError: 475335:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7799.648384] LustreError: 475336:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7799.651997] LustreError: 475335:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7800.715323] Lustre: Mounted lustre-client [ 7801.270660] LustreError: 475436:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e0535000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7801.275869] LustreError: 475436:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7801.313111] Lustre: Unmounted lustre-client [ 7802.418382] Key type lgssc unregistered [ 7802.548695] LNet: 475678:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7802.551643] LNetError: 475678:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7802.562401] LNet: Removed LNI 192.168.203.5@tcp [ 7802.844101] Key type .llcrypt unregistered [ 7802.845086] Key type ._llcrypt unregistered [ 7803.155685] Key type ._llcrypt registered [ 7803.156612] Key type .llcrypt registered [ 7803.373715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7803.378172] alg: No test for adler32 (adler32-zlib) [ 7804.250991] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7804.333886] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7805.919118] Key type lgssc registered [ 7806.340213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7809.765097] Lustre: DEBUG MARKER: Iteration 43 [ 7809.864140] LustreError: 476507:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7809.867043] LustreError: 476508:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7809.870163] LustreError: 476507:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7810.928537] Lustre: Mounted lustre-client [ 7811.475373] LustreError: 476609:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c4de5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7811.478493] LustreError: 476609:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7811.479482] LustreError: 476610:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7811.530636] Lustre: Unmounted lustre-client [ 7812.528652] Key type lgssc unregistered [ 7812.637517] LNet: 476852:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7812.640065] LNetError: 476852:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7812.646730] LNet: Removed LNI 192.168.203.5@tcp [ 7812.884088] Key type .llcrypt unregistered [ 7812.885274] Key type ._llcrypt unregistered [ 7813.127959] Key type ._llcrypt registered [ 7813.129064] Key type .llcrypt registered [ 7813.326286] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7813.330570] alg: No test for adler32 (adler32-zlib) [ 7814.181673] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7814.262966] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7815.839136] Key type lgssc registered [ 7816.192292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7819.826958] Lustre: DEBUG MARKER: Iteration 44 [ 7819.942649] LustreError: 477680:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7819.942684] LustreError: 477681:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7819.949187] LustreError: 477680:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7821.006941] Lustre: Mounted lustre-client [ 7821.466952] LustreError: 477784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3d9a99800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7821.469986] LustreError: 477785:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7821.470725] LustreError: 477784:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7821.497562] Lustre: Unmounted lustre-client [ 7822.511450] Key type lgssc unregistered [ 7822.649418] LNet: 478027:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7822.652120] LNetError: 478027:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7822.659805] LNet: Removed LNI 192.168.203.5@tcp [ 7822.895118] Key type .llcrypt unregistered [ 7822.896698] Key type ._llcrypt unregistered [ 7823.193272] Key type ._llcrypt registered [ 7823.194433] Key type .llcrypt registered [ 7823.352699] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7823.359201] alg: No test for adler32 (adler32-zlib) [ 7824.228944] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7824.314124] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7825.903157] Key type lgssc registered [ 7826.305157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7829.615764] Lustre: DEBUG MARKER: Iteration 45 [ 7829.726968] LustreError: 478858:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7829.727027] LustreError: 478857:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7829.732653] LustreError: 478858:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7830.791435] Lustre: Mounted lustre-client [ 7831.215261] LustreError: 478962:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c5f1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7831.217561] LustreError: 478961:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7831.218424] LustreError: 478962:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7831.223067] LustreError: 478961:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7831.267102] Lustre: Unmounted lustre-client [ 7832.202260] Key type lgssc unregistered [ 7832.321521] LNet: 479204:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7832.323791] LNetError: 479204:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7832.332719] LNet: Removed LNI 192.168.203.5@tcp [ 7832.567082] Key type .llcrypt unregistered [ 7832.568155] Key type ._llcrypt unregistered [ 7832.834943] Key type ._llcrypt registered [ 7832.836067] Key type .llcrypt registered [ 7833.003514] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7833.007949] alg: No test for adler32 (adler32-zlib) [ 7833.856221] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7833.935321] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7835.511112] Key type lgssc registered [ 7835.892597] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7839.468973] Lustre: DEBUG MARKER: Iteration 46 [ 7839.576223] LustreError: 480032:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7839.576268] LustreError: 480033:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7839.580403] LustreError: 480032:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7840.626366] Lustre: Mounted lustre-client [ 7841.065774] LustreError: 480135:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3ff564000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7841.070179] LustreError: 480135:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7841.072074] LustreError: 480135:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7841.110334] Lustre: Unmounted lustre-client [ 7842.180144] Key type lgssc unregistered [ 7842.306748] LNet: 480379:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7842.308881] LNetError: 480379:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7842.319754] LNet: Removed LNI 192.168.203.5@tcp [ 7842.563094] Key type .llcrypt unregistered [ 7842.564234] Key type ._llcrypt unregistered [ 7842.875430] Key type ._llcrypt registered [ 7842.878443] Key type .llcrypt registered [ 7843.147378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7843.154657] alg: No test for adler32 (adler32-zlib) [ 7844.016341] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7844.100335] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7845.687122] Key type lgssc registered [ 7846.105787] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7849.717605] Lustre: DEBUG MARKER: Iteration 47 [ 7849.844571] LustreError: 481208:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7849.844602] LustreError: 481207:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7849.848468] LustreError: 481208:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7850.902407] Lustre: Mounted lustre-client [ 7850.904051] Lustre: Skipped 1 previous similar message [ 7851.433964] LustreError: 481309:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3f04c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7851.437524] LustreError: 481309:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7851.437833] LustreError: 481308:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7851.489451] Lustre: Unmounted lustre-client [ 7852.547712] Key type lgssc unregistered [ 7852.665784] LNet: 481551:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7852.668213] LNetError: 481551:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7852.677394] LNet: Removed LNI 192.168.203.5@tcp [ 7852.944100] Key type .llcrypt unregistered [ 7852.945136] Key type ._llcrypt unregistered [ 7853.231994] Key type ._llcrypt registered [ 7853.233095] Key type .llcrypt registered [ 7853.463374] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7853.469376] alg: No test for adler32 (adler32-zlib) [ 7854.325785] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7854.409150] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7855.999128] Key type lgssc registered [ 7856.380281] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7860.172290] Lustre: DEBUG MARKER: Iteration 48 [ 7860.294296] LustreError: 482379:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7860.294688] LustreError: 482381:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7860.300186] LustreError: 482379:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7861.361827] Lustre: Mounted lustre-client [ 7861.792784] LustreError: 482483:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c7213800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7861.794776] LustreError: 482482:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7861.796444] LustreError: 482483:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7861.846446] Lustre: Unmounted lustre-client [ 7862.750839] Key type lgssc unregistered [ 7862.869353] LNet: 482726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7862.872951] LNetError: 482726:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7862.885735] LNet: Removed LNI 192.168.203.5@tcp [ 7863.111101] Key type .llcrypt unregistered [ 7863.112183] Key type ._llcrypt unregistered [ 7863.327579] Key type ._llcrypt registered [ 7863.328474] Key type .llcrypt registered [ 7863.498822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7863.505363] alg: No test for adler32 (adler32-zlib) [ 7864.353295] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7864.439520] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7866.023097] Key type lgssc registered [ 7866.364906] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7870.000404] Lustre: DEBUG MARKER: Iteration 49 [ 7870.102738] LustreError: 483553:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7870.102778] LustreError: 483554:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7870.106413] LustreError: 483553:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7871.160758] Lustre: Mounted lustre-client [ 7871.161743] Lustre: Skipped 1 previous similar message [ 7871.590770] LustreError: 483658:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c9693800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7871.592774] LustreError: 483657:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7871.593576] LustreError: 483658:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7871.632794] Lustre: Unmounted lustre-client [ 7872.553446] Key type lgssc unregistered [ 7872.670395] LNet: 483901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7872.672418] LNetError: 483901:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7872.678751] LNet: Removed LNI 192.168.203.5@tcp [ 7872.905121] Key type .llcrypt unregistered [ 7872.907121] Key type ._llcrypt unregistered [ 7873.153217] Key type ._llcrypt registered [ 7873.154279] Key type .llcrypt registered [ 7873.341377] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7873.345875] alg: No test for adler32 (adler32-zlib) [ 7874.196299] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7874.271158] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7875.847103] Key type lgssc registered [ 7876.201477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7879.822893] Lustre: DEBUG MARKER: Iteration 50 [ 7879.945195] LustreError: 484730:0:(llite_lib.c:1395:ll_fill_super()) cfs_race id 1417 sleeping [ 7879.945557] LustreError: 484729:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 waking [ 7879.950090] LustreError: 484730:0:(llite_lib.c:1395:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7881.012967] Lustre: Mounted lustre-client [ 7881.471706] LustreError: 484833:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3fd428000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7881.475825] LustreError: 484833:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7881.510086] Lustre: Unmounted lustre-client [ 7882.438105] Key type lgssc unregistered [ 7882.552482] LNet: 485076:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7882.554593] LNetError: 485076:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7882.563612] LNet: Removed LNI 192.168.203.5@tcp [ 7882.794098] Key type .llcrypt unregistered [ 7882.795171] Key type ._llcrypt unregistered [ 7883.060263] Key type ._llcrypt registered [ 7883.064106] Key type .llcrypt registered [ 7883.236790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7883.241300] alg: No test for adler32 (adler32-zlib) [ 7884.092490] Lustre: Lustre: Build Version: 2.16.58_106_g01a4b29 [ 7884.171745] LNet: Added LNI 192.168.203.5@tcp [8/256/0/180] [ 7885.751215] Key type lgssc registered [ 7886.140880] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7890.432967] Lustre: Mounted lustre-client [ 7892.756531] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 17:58:55 (1760133535) [ 7909.343134] Lustre: 486415:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760133536/real 1760133536] req@ffff9ec3e9bb7100 x1845633773217664/t0(0) o36->lustre-MDT0000-mdc-ffff9ec3e0317800@192.168.203.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1760133552 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7909.351374] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7909.359774] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 7925.727116] Lustre: 486415:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760133552/real 1760133552] req@ffff9ec3e9bb7100 x1845633773217664/t0(0) o36->lustre-MDT0000-mdc-ffff9ec3e0317800@192.168.203.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1760133568 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7925.736729] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7925.745078] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 7941.087108] Lustre: 486415:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760133568/real 1760133568] req@ffff9ec3e9bb7100 x1845633773217664/t0(0) o36->lustre-MDT0000-mdc-ffff9ec3e0317800@192.168.203.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1760133584 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7941.096212] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7941.104696] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 7957.471131] Lustre: 486415:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760133584/real 1760133584] req@ffff9ec3e9bb7100 x1845633773217664/t0(0) o36->lustre-MDT0000-mdc-ffff9ec3e0317800@192.168.203.105@tcp:12/10 lens 496/440 e 0 to 1 dl 1760133600 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7957.478233] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection to lustre-MDT0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7957.488454] Lustre: lustre-MDT0000-mdc-ffff9ec3e0317800: Connection restored to 192.168.203.105@tcp (at 192.168.203.105@tcp) [ 7958.037110] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 18:00:00 (1760133600) [ 7963.632455] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 18:00:06 (1760133606) [ 7966.876236] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 18:00:09 (1760133609) [ 7969.003303] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 18:00:11 (1760133611) [ 7975.973983] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 18:00:18 (1760133618) [ 7988.149092] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 18:00:30 (1760133630) [ 7990.401064] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 18:00:32 (1760133632) [ 8008.159100] Lustre: 485266:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1760133635/real 1760133635] req@ffff9ec3fbe24380 x1845633774245760/t0(0) o4->lustre-OST0000-osc-ffff9ec3e0317800@192.168.203.105@tcp:6/4 lens 4584/448 e 0 to 1 dl 1760133651 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8008.166141] Lustre: lustre-OST0000-osc-ffff9ec3e0317800: Connection to lustre-OST0000 (at 192.168.203.105@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8075.786385] Lustre: DEBUG MARKER: oleg305-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9ec3c9693000.ost_server_uuid 50 [ 8076.324752] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9ec3c9693000.ost_server_uuid in IDLE state after 0 sec [ 8076.903239] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8077.495244] Lustre: DEBUG MARKER: == sanityn test complete, duration 7857 sec ============== 18:02:00 (1760133720) [ 8078.012985] Lustre: DEBUG MARKER: === sanityn: start cleanup 18:02:00 (1760133720) === [ 8150.502325] LustreError: 492695:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3c9693000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8150.505880] LustreError: 492695:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8150.520065] Lustre: Unmounted lustre-client [ 8151.625897] Lustre: DEBUG MARKER: === sanityn: finish cleanup 18:03:14 (1760133794) === [ 8151.909974] LustreError: 492999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9ec3e0317800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8151.912775] LustreError: 492999:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8151.917888] LustreError: 492999:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8151.919491] LustreError: 492999:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8151.943688] Lustre: Unmounted lustre-client [ 8186.588606] Key type lgssc unregistered [ 8186.728636] LNet: 493683:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8186.730941] LNetError: 493683:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8186.739572] LNet: Removed LNI 192.168.203.5@tcp [ 8187.013113] Key type .llcrypt unregistered [ 8187.014159] Key type ._llcrypt unregistered