[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 446333777 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003120] x2apic enabled [ 0.004012] Switched APIC routing to physical x2apic. [ 0.005016] kvm-guest: setup PV IPIs [ 0.007917] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010134] LSM: Security Framework initializing [ 0.011066] Yama: becoming mindful. [ 0.012040] SELinux: Initializing. [ 0.013086] *** VALIDATE selinux *** [ 0.022054] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027287] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028159] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029128] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031128] *** VALIDATE tmpfs *** [ 0.032453] *** VALIDATE proc *** [ 0.034074] *** VALIDATE cgroup *** [ 0.035010] *** VALIDATE cgroup2 *** [ 0.036279] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038147] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040039] Spectre V2 : User space: Vulnerable [ 0.041009] Speculative Store Bypass: Vulnerable [ 0.043849] debug: unmapping init [mem 0xffffffffbaa59000-0xffffffffbaa60fff] [ 0.045203] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046715] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047024] ... version: 2 [ 0.048010] ... bit width: 48 [ 0.049020] ... generic registers: 4 [ 0.050015] ... value mask: 0000ffffffffffff [ 0.051015] ... max period: 00007fffffffffff [ 0.052015] ... fixed-purpose events: 3 [ 0.053015] ... event mask: 000000070000000f [ 0.054314] rcu: Hierarchical SRCU implementation. [ 0.056480] smp: Bringing up secondary CPUs ... [ 0.057600] x86: Booting SMP configuration: [ 0.058032] .... node #0, CPUs: #1 #2 #3 [ 0.061394] smp: Brought up 1 node, 4 CPUs [ 0.063013] smpboot: Max logical packages: 1 [ 0.064018] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.205053] node 0 deferred pages initialised in 138ms [ 0.208269] devtmpfs: initialized [ 0.209353] x86/mm: Memory block size: 128MB [ 0.214282] gcov: version magic: 0x41383552 [ 0.217340] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.220163] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.223470] pinctrl core: initialized pinctrl subsystem [ 0.225250] [ 0.226011] ************************************************************* [ 0.229031] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.231015] ** ** [ 0.234021] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.236016] ** ** [ 0.238021] ** This means that this kernel is built to expose internal ** [ 0.241017] ** IOMMU data structures, which may compromise security on ** [ 0.243016] ** your system. ** [ 0.246019] ** ** [ 0.248017] ** If you see this message and you are not debugging the ** [ 0.251021] ** kernel, report this immediately to your vendor! ** [ 0.254020] ** ** [ 0.256016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.259021] ************************************************************* [ 0.262840] NET: Registered protocol family 16 [ 0.265504] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.268086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.270084] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.274142] cpuidle: using governor menu [ 0.275820] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.278375] PCI: Using configuration type 1 for base access [ 0.281131] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.293071] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.294042] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.296043] cryptd: max_cpu_qlen set to 1000 [ 0.298236] ACPI: Added _OSI(Module Device) [ 0.300022] ACPI: Added _OSI(Processor Device) [ 0.302015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.304014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.308882] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.316250] ACPI: Interpreter enabled [ 0.317069] ACPI: PM: (supports S0 S3 S4 S5) [ 0.319027] ACPI: Using IOAPIC for interrupt routing [ 0.320165] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.324480] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.336017] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.338056] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.341029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.344110] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.349736] acpiphp: Slot [2] registered [ 0.351196] acpiphp: Slot [5] registered [ 0.353183] acpiphp: Slot [6] registered [ 0.354210] acpiphp: Slot [3] registered [ 0.356142] acpiphp: Slot [4] registered [ 0.357123] acpiphp: Slot [7] registered [ 0.359135] acpiphp: Slot [8] registered [ 0.360110] acpiphp: Slot [9] registered [ 0.362130] acpiphp: Slot [10] registered [ 0.364182] acpiphp: Slot [11] registered [ 0.365119] acpiphp: Slot [12] registered [ 0.367132] acpiphp: Slot [13] registered [ 0.368170] acpiphp: Slot [14] registered [ 0.370175] acpiphp: Slot [15] registered [ 0.371125] acpiphp: Slot [16] registered [ 0.373244] acpiphp: Slot [17] registered [ 0.374125] acpiphp: Slot [18] registered [ 0.376121] acpiphp: Slot [19] registered [ 0.378158] acpiphp: Slot [20] registered [ 0.380121] acpiphp: Slot [21] registered [ 0.382117] acpiphp: Slot [22] registered [ 0.384119] acpiphp: Slot [23] registered [ 0.386151] acpiphp: Slot [24] registered [ 0.387161] acpiphp: Slot [25] registered [ 0.389116] acpiphp: Slot [26] registered [ 0.391141] acpiphp: Slot [27] registered [ 0.392113] acpiphp: Slot [28] registered [ 0.394139] acpiphp: Slot [29] registered [ 0.395119] acpiphp: Slot [30] registered [ 0.397104] acpiphp: Slot [31] registered [ 0.398067] PCI host bridge to bus 0000:00 [ 0.400032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.403051] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.406035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.409037] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.413039] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.415038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.417203] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.420061] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.423292] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.430546] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.436012] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.438020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.440026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.442024] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.445558] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.448841] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.451046] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.454749] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.457963] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.469017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.474022] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.480023] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.488024] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.494025] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.512023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.523237] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.531017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.538015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.559025] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.573220] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.576422] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.578514] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.581517] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.584263] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.590042] iommu: Default domain type: Passthrough [ 0.592564] SCSI subsystem initialized [ 0.594200] ACPI: bus type USB registered [ 0.596162] usbcore: registered new interface driver usbfs [ 0.598148] usbcore: registered new interface driver hub [ 0.601230] usbcore: registered new device driver usb [ 0.603215] pps_core: LinuxPPS API ver. 1 registered [ 0.605015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.608095] PTP clock support registered [ 0.611057] EDAC MC: Ver: 3.0.0 [ 0.613145] PCI: Using ACPI for IRQ routing [ 0.615973] NetLabel: Initializing [ 0.617019] NetLabel: domain hash size = 128 [ 0.619012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.621116] NetLabel: unlabeled traffic allowed by default [ 0.624127] vgaarb: loaded [ 0.625323] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.627018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.634126] clocksource: Switched to clocksource kvm-clock [ 0.745314] VFS: Disk quotas dquot_6.6.0 [ 0.747384] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.750510] *** VALIDATE ramfs *** [ 0.751987] *** VALIDATE hugetlbfs *** [ 0.753962] pnp: PnP ACPI init [ 0.756660] pnp: PnP ACPI: found 6 devices [ 0.777805] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.781349] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.783705] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.786085] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.788667] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.791285] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.794364] NET: Registered protocol family 2 [ 0.797346] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.803275] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.808761] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.815261] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.819680] TCP: Hash tables configured (established 65536 bind 65536) [ 0.822598] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.826195] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.829315] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.832624] NET: Registered protocol family 1 [ 0.835599] RPC: Registered named UNIX socket transport module. [ 0.838159] RPC: Registered udp transport module. [ 0.839914] RPC: Registered tcp transport module. [ 0.842083] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.844810] NET: Registered protocol family 44 [ 0.846648] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.849146] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.851521] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.853681] PCI: CLS 0 bytes, default 64 [ 0.855192] Unpacking initramfs... [ 2.298336] debug: unmapping init [mem 0xffff8d93bcc64000-0xffff8d93bffcffff] [ 2.301514] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.303587] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.305966] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.804824] Initialise system trusted keyrings [ 2.806889] Key type blacklist registered [ 2.809112] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.819223] zbud: loaded [ 2.822634] *** VALIDATE nfs *** [ 2.825454] *** VALIDATE nfs4 *** [ 2.827398] pstore: using deflate compression [ 2.831059] Platform Keyring initialized [ 2.935833] NET: Registered protocol family 38 [ 2.937675] Key type asymmetric registered [ 2.939365] Asymmetric key parser 'x509' registered [ 2.941423] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.945548] io scheduler mq-deadline registered [ 2.947159] io scheduler kyber registered [ 2.948509] io scheduler bfq registered [ 2.950187] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.953667] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.956660] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.959864] ACPI: Power Button [PWRF] [ 2.966290] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.973779] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.989028] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.017913] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.047107] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.052978] Non-volatile memory driver v1.3 [ 3.056724] Linux agpgart interface v0.103 [ 3.089447] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.092614] vda: detected capacity change from 0 to 68579328 [ 3.108228] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.111652] vdb: detected capacity change from 0 to 1073741824 [ 3.123504] libphy: Fixed MDIO Bus: probed [ 3.128844] usbcore: registered new interface driver usbserial_generic [ 3.132210] usbserial: USB Serial support registered for generic [ 3.135161] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.140189] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.142590] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.146435] mousedev: PS/2 mouse device common for all mice [ 3.149850] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.151433] rtc_cmos 00:05: RTC can wake from S4 [ 3.157231] rtc_cmos 00:05: registered as rtc0 [ 3.157240] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.158747] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.164800] intel_pstate: CPU model not supported [ 3.167452] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.168380] hid: raw HID events driver (C) Jiri Kosina [ 3.173208] usbcore: registered new interface driver usbhid [ 3.174892] usbhid: USB HID core driver [ 3.176905] drop_monitor: Initializing network drop monitor service [ 3.179118] Initializing XFRM netlink socket [ 3.180786] NET: Registered protocol family 10 [ 3.183522] Segment Routing with IPv6 [ 3.184811] NET: Registered protocol family 17 [ 3.186787] mpls_gso: MPLS GSO support [ 3.192344] RAS: Correctable Errors collector initialized. [ 3.194499] AVX version of gcm_enc/dec engaged. [ 3.196293] AES CTR mode by8 optimization enabled [ 3.273163] sched_clock: Marking stable (3273128447, 0)->(4196277739, -923149292) [ 3.277256] registered taskstats version 1 [ 3.279371] Loading compiled-in X.509 certificates [ 3.281035] zswap: loaded using pool lzo/zbud [ 3.306678] Key type big_key registered [ 3.318798] Key type encrypted registered [ 3.321238] ima: No TPM chip found, activating TPM-bypass! [ 3.323240] ima: Allocated hash algorithm: sha1 [ 3.324481] ima: No architecture policies found [ 3.325954] evm: Initialising EVM extended attributes: [ 3.327524] evm: security.selinux [ 3.328457] evm: security.ima [ 3.329417] evm: security.capability [ 3.330489] evm: HMAC attrs: 0x1 [ 3.333147] rtc_cmos 00:05: setting system clock to 2026-04-09 19:20:23 UTC (1775762423) [ 3.339577] debug: unmapping init [mem 0xffffffffbba03000-0xffffffffbbbfffff] [ 3.343360] debug: unmapping init [mem 0xffffffffba782000-0xffffffffbaa58fff] [ 3.353125] Write protecting the kernel read-only data: 28672k [ 3.356357] debug: unmapping init [mem 0xffffffffb8e03000-0xffffffffb8ffffff] [ 3.358830] debug: unmapping init [mem 0xffffffffb9714000-0xffffffffb97fffff] [ 3.391686] 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.400669] systemd[1]: Detected virtualization kvm. [ 3.402418] systemd[1]: Detected architecture x86-64. [ 3.403843] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.429207] systemd[1]: No hostname configured. [ 3.430939] systemd[1]: Set hostname to . [ 3.433298] random: systemd: uninitialized urandom read (16 bytes read) [ 3.435808] systemd[1]: Initializing machine ID from random generator. [ 3.564682] random: systemd: uninitialized urandom read (16 bytes read) [ 3.567407] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.572765] random: systemd: uninitialized urandom read (16 bytes read) [ 3.576595] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.581293] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Journal Service... Starting Setup Virtual Console... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.226958] device-mapper: uevent: version 1.0.3 [ 4.229183] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.986688] random: fast init done [ 4.999694] virtio_net virtio0 ens2: renamed from eth0 [ 5.033281] scsi host0: ata_piix [ 5.057102] scsi host1: ata_piix [ 5.059179] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.061562] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.438592] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.836752] random: crng init done [ 9.838208] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.296937] 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.548360] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.817701] SELinux: Disabled at runtime. [ 11.875592] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.883805] systemd[1]: Detected virtualization kvm. [ 11.885461] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.383956] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.387555] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.392638] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.398377] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.402280] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.414072] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.418595] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. Mounting Kernel Debug File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Local Encrypted Volumes. Mounting POSIX Message Queue File System... [ 12.610825] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 12.891105] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.168250] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.176571] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.257921] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.275707] EDAC sbridge: Ver: 1.1.2 [ 14.365549] Key type dns_resolver registered [ 14.668094] NFS: Registering the id_resolver key type [ 14.670196] Key type id_resolver registered [ 14.671926] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg304-client login: [ 67.125847] libcfs: loading out-of-tree module taints kernel. [ 67.200378] Key type ._llcrypt registered [ 67.201821] Key type .llcrypt registered [ 68.148181] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 68.171777] alg: No test for adler32 (adler32-zlib) [ 69.883465] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [ 70.752389] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [ 72.648266] Key type lgssc registered [ 74.864133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 120.953277] hrtimer: interrupt took 2417880 ns [ 244.932178] Lustre: Mounted lustre-client [ 250.117067] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 269.264100] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing check_logdir /tmp/testlogs/ [ 270.824032] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 23s idle [ 274.244952] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing yml_node [ 279.956627] Lustre: DEBUG MARKER: Client: 2.17.51.77 [ 284.051309] Lustre: DEBUG MARKER: MDS: 2.17.51.77 [ 288.137890] Lustre: DEBUG MARKER: OSS: 2.17.51.77 [ 290.875920] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Apr 9 15:25:08 EDT 2026 [ 312.226698] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 314.050286] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 316.203938] Lustre: DEBUG MARKER: === sanityn: start setup 15:25:34 (1775762734) === [ 316.873179] Lustre: Mounted lustre-client [ 320.603034] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing check_config_client /mnt/lustre [ 344.526934] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 361.504045] Lustre: DEBUG MARKER: === sanityn: finish setup 15:26:19 (1775762779) === [ 364.759754] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 15:26:22 (1775762782) [ 373.840746] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 15:26:32 (1775762792) [ 380.919074] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 15:26:39 (1775762799) [ 388.361819] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 15:26:47 (1775762807) [ 396.817946] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 15:26:55 (1775762815) [ 403.233665] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 15:27:01 (1775762821) [ 410.806329] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 15:27:08 (1775762828) [ 420.274977] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 15:27:18 (1775762838) [ 430.287104] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 15:27:28 (1775762848) [ 439.626770] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 15:27:37 (1775762857) [ 448.689930] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 15:27:47 (1775762867) [ 455.139226] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 24s idle [ 455.145266] Lustre: Skipped 1 previous similar message [ 456.832711] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 15:27:55 (1775762875) [ 463.211898] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 15:28:01 (1775762881) [ 470.089529] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 15:28:08 (1775762888) [ 476.811811] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 15:28:15 (1775762895) [ 485.359469] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 15:28:23 (1775762903) [ 490.980816] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 22s idle [ 494.061667] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 15:28:32 (1775762912) [ 502.411753] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 15:28:41 (1775762921) [ 511.928227] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 15:28:50 (1775762930) [ 519.349063] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 15:28:58 (1775762938) [ 527.094548] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 15:29:06 (1775762946) [ 527.870210] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 671.551362] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 15:31:30 (1775763090) [ 682.614447] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 15:31:40 (1775763100) [ 690.422685] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 15:31:49 (1775763109) [ 697.974678] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 15:31:56 (1775763116) [ 706.050646] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 15:32:04 (1775763124) [ 714.918163] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 15:32:13 (1775763133) [ 717.255154] Lustre: DEBUG MARKER: chmod [ 725.931623] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 15:32:24 (1775763144) [ 1770.976469] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: disconnect after 21s idle [ 1771.528606] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 15:49:50 (1775764190) [ 1960.418027] Lustre: lustre-OST0001-osc-ffff8d9403fdd000: disconnect after 20s idle [ 2043.591639] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 15:54:22 (1775764462) [ 2182.522144] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 15:56:40 (1775764600) [ 2354.780150] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 15:59:33 (1775764773) [ 2398.417285] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 16:00:17 (1775764817) [ 2400.737171] Lustre: lustre-OST0001-osc-ffff8d9403fdd000: disconnect after 24s idle [ 2406.123884] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 16:00:24 (1775764824) [ 2407.389532] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2407.505384] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2407.615888] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2407.734463] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2407.845223] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2407.986336] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.082794] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.170549] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.258029] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.353446] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.451004] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.545842] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.637701] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.718281] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.803390] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2408.895543] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.009216] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.099386] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.214319] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.306848] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.413612] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.530738] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.620212] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.708613] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.797941] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2409.895602] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.019450] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.106288] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.192315] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.286151] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.369537] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.452817] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.532350] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.605084] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.677133] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.755123] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.836721] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2410.918539] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.004725] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.092165] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.172158] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.286126] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.380248] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.493622] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.585724] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.662879] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.736812] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.829528] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.911283] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2411.982097] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.083368] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.187670] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.279601] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.371160] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.441767] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.513415] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.590448] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.642209] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.723940] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.829686] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2412.947638] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.041868] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.157291] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.291568] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.393952] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.474200] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.574816] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.655958] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.733670] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.822817] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.894686] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2413.973788] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.063839] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.145936] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.246924] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.335861] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.454846] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.539481] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.614432] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.675726] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.764243] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.851772] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2414.934952] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.021469] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.124856] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.204149] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.301234] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.385108] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.463802] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.550892] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.637810] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.717509] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.783155] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.859760] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2415.942329] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.010624] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.077292] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.139313] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.227877] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.300929] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.384655] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.452924] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.572564] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.643979] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.761098] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.842166] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2416.956659] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.043296] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.133096] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.223177] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.294533] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.367307] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.430219] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.496855] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.556780] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.616372] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.724901] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.836653] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2417.945263] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.020539] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.096693] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.165795] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.233408] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.311995] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.386704] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.451489] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.538866] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.603388] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.676696] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.784914] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.844173] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.918747] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2418.989515] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.079237] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.150971] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.205584] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.275354] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.358936] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.429613] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.503973] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.570273] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.645628] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.742894] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.823833] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.906312] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2419.977190] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.039368] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.111565] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.170424] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.236367] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.288116] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.350559] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.405030] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.476370] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.526551] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.593384] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.660895] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.742532] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.807710] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.874448] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2420.966571] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.027558] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.117427] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.188393] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.216170] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 20s idle [ 2421.258815] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.321569] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.389756] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.496334] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.571236] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.640372] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.726740] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.794315] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.860509] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2421.964618] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.047639] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.132812] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.211454] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.303934] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.359203] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.461076] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.531705] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.626840] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.709401] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.790318] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.844984] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2422.934909] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.016483] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.104554] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.182392] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.260950] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.357586] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.509215] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.591227] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.671690] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.747597] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.827273] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.906303] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2423.988019] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.061930] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.146626] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.233818] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.324036] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.419858] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.502766] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.601342] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.677926] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.762148] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.817772] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2424.923903] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.018542] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.105586] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.202032] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.279987] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.370300] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.463589] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.616338] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.728563] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.793269] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2425.905393] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2426.010508] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2426.079478] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2426.183631] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2426.274723] rw_seq_cst_vs_d (32432): drop_caches: 3 [ 2434.012517] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 16:00:52 (1775764852) [ 2434.590868] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2434.771783] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2434.817817] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2434.890949] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2434.971865] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.035235] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.073800] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.175532] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.338903] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.403794] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.535541] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.653637] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.758128] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.805379] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2435.868805] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.038269] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.137766] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.251388] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.313886] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.482169] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.522675] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.611326] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.709743] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.763881] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.909457] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2436.990937] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.055594] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.139155] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.172374] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.238679] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.348809] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.528181] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.602587] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.835199] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.909472] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2437.995436] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.086223] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.348471] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.448239] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.690141] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.742718] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2438.893189] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.108322] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.229932] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.367205] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.556333] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.754833] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.799436] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2439.895412] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.021219] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.160533] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.241506] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.418610] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.478630] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.640938] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.694795] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.857571] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2440.963984] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.007066] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.076937] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.192826] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.345979] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.522937] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.610136] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.777337] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2441.950480] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.156104] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.438206] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.501382] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.696716] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.815964] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2442.963350] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.013896] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.159338] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.334183] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.451170] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.479689] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.726951] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.815431] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2443.895927] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.034507] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.073826] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.156668] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.316929] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.528088] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.588765] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.672081] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.770340] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.835257] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2444.984861] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.126302] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.224589] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.455758] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.484364] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.621422] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.656564] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.700125] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.801884] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2445.984144] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.104859] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.170888] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.356588] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.448479] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.534220] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.645908] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.758231] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2446.937311] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.013415] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.182630] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.401436] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.450768] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.541093] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.613200] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.707807] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.774221] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2447.916703] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.006848] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.173473] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.232883] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.374235] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.466911] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.536339] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.697835] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.883728] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2448.923593] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.196702] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.268490] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.325612] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.434637] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.532862] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.761081] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2449.836255] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.001217] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.150275] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.206341] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.275750] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.425152] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.474571] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.670702] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2450.839140] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.067483] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.244164] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.295083] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.399440] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.571536] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.658866] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.791178] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.875396] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2451.942932] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 20s idle [ 2451.960788] Lustre: Skipped 1 previous similar message [ 2451.996881] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.051035] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.196964] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.276774] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.367759] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.498737] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.635911] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.693620] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.813333] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2452.890435] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.040317] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.124568] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.189612] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.287386] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.325469] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.515992] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.661264] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.832645] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.898689] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2453.984309] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2454.052844] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2454.207366] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2454.273766] rw_seq_cst_vs_d (33022): drop_caches: 3 [ 2462.131613] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 16:01:20 (1775764880) [ 2470.622846] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 16:01:29 (1775764889) [ 2479.584866] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 16:01:37 (1775764897) [ 2515.313284] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 16:02:13 (1775764933) [ 2516.082322] bash (35523): drop_caches: 3 [ 2519.256391] bash (35523): drop_caches: 3 [ 2522.504813] bash (35523): drop_caches: 3 [ 2525.718718] bash (35523): drop_caches: 3 [ 2528.909967] bash (35523): drop_caches: 3 [ 2532.087785] bash (35523): drop_caches: 3 [ 2535.267607] bash (35523): drop_caches: 3 [ 2538.406223] bash (35523): drop_caches: 3 [ 2541.533138] bash (35523): drop_caches: 3 [ 2544.674040] bash (35523): drop_caches: 3 [ 2548.265496] bash (35523): drop_caches: 3 [ 2551.486539] bash (35523): drop_caches: 3 [ 2555.013406] bash (35523): drop_caches: 3 [ 2558.121355] bash (35523): drop_caches: 3 [ 2561.260133] bash (35523): drop_caches: 3 [ 2564.377843] bash (35523): drop_caches: 3 [ 2567.576943] bash (35523): drop_caches: 3 [ 2572.438560] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 16:03:11 (1775764991) [ 2583.241829] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 16:03:22 (1775765002) [ 2702.525144] LustreError: lustre-OST0000-osc-ffff8d9403fdd000: operation ost_write to node 192.168.203.104@tcp failed: rc = -107 [ 2702.536258] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2702.565792] LustreError: lustre-OST0000-osc-ffff8d9403fdd000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2702.581776] Lustre: 2406:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.104@tcp:/lustre/fid: [0x200000402:0x6c:0x0]// may get corrupted (rc -5) [ 2702.606849] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 2702.640946] LustreError: lustre-OST0001-osc-ffff8d9408124800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2702.660381] Lustre: 2405:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.203.104@tcp:/lustre/fid: [0x200000402:0x6d:0x0]// may get corrupted (rc -5) [ 2718.167221] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 16:05:37 (1775765137) [ 2727.436943] Lustre: DEBUG MARKER: loop 5 [ 2733.045361] Lustre: DEBUG MARKER: loop 10 [ 2733.536304] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 24s idle [ 2733.551820] Lustre: Skipped 1 previous similar message [ 2738.324282] Lustre: DEBUG MARKER: loop 15 [ 2744.313434] Lustre: DEBUG MARKER: loop 20 [ 2752.828281] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 16:06:11 (1775765171) [ 2761.009496] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 16:06:19 (1775765179) [ 2768.998547] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 16:06:27 (1775765187) [ 2774.497668] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 21s idle [ 2774.501749] Lustre: Skipped 1 previous similar message [ 2838.761207] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 16:07:37 (1775765257) [ 2845.814699] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 16:07:44 (1775765264) [ 2853.042886] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 16:07:51 (1775765271) [ 2859.698342] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 16:07:58 (1775765278) [ 2867.772768] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 16:08:06 (1775765286) [ 2874.948495] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 16:08:13 (1775765293) [ 2884.299724] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 16:08:23 (1775765303) [ 2892.481695] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2894.531716] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 16:08:32 (1775765312) [ 2904.575567] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 16:08:43 (1775765323) [ 2905.581491] Lustre: *** cfs_fail_loc=314, val=0*** [ 2906.656647] Lustre: *** cfs_fail_loc=314, val=0*** [ 2906.661027] Lustre: Skipped 2 previous similar messages [ 2907.617163] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 21s idle [ 2907.628912] Lustre: Skipped 3 previous similar messages [ 2914.174321] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 16:08:53 (1775765333) [ 2925.597870] Lustre: *** cfs_fail_loc=314, val=0*** [ 2928.105473] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2928.127594] Lustre: Skipped 1 previous similar message [ 2928.143709] LustreError: lustre-OST0000-osc-ffff8d9403fdd000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2928.187412] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 2928.213467] Lustre: Skipped 1 previous similar message [ 2933.972847] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 16:09:12 (1775765352) [ 2934.419075] LustreError: 47056:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2937.451244] LustreError: 47056:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2945.489216] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 16:09:23 (1775765363) [ 2953.934826] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 16:09:32 (1775765372) [ 2963.034845] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2964.789612] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 16:09:43 (1775765383) [ 2967.191975] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2969.201849] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 16:09:48 (1775765388) [ 2974.193337] Lustre: lustre-MDT0000-mdc-ffff8d9408124800: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2979.299698] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: disconnect after 24s idle [ 2989.552805] LustreError: MGC192.168.203.104@tcp: Connection to MGS (at 192.168.203.104@tcp) was lost; in progress operations using this service will fail [ 2989.569981] Lustre: Evicted from MGS (at 192.168.203.104@tcp) after server handle changed from 0xa0246d0932e21c9e to 0xa0246d0932ec399b [ 2989.591213] Lustre: MGC192.168.203.104@tcp: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 3033.630402] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 16:10:52 (1775765452) [ 3100.562969] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 16:11:59 (1775765519) [ 3125.633224] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 16:12:24 (1775765544) [ 3138.016712] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: disconnect after 22s idle [ 3138.023198] Lustre: Skipped 1 previous similar message [ 3183.074060] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3183.089440] Lustre: Skipped 1 previous similar message [ 3183.099993] LustreError: lustre-OST0000-osc-ffff8d9403fdd000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3183.123445] LustreError: lustre-OST0000-osc-ffff8d9408124800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3183.126065] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 3183.159704] Lustre: Skipped 3 previous similar messages [ 3199.480586] Lustre: lustre-OST0001-osc-ffff8d9408124800: Connection to lustre-OST0001 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3199.500694] Lustre: Skipped 1 previous similar message [ 3199.513501] LustreError: lustre-OST0001-osc-ffff8d9408124800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 3199.524151] Lustre: lustre-OST0001-osc-ffff8d9408124800: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [ 3214.741163] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3216.231704] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid in IDLE state after 0 sec [ 3220.259355] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3221.951618] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid in FULL state after 0 sec [ 3226.932830] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3228.376391] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid in IDLE state after 0 sec [ 3232.817945] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3234.606387] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid in FULL state after 0 sec [ 3245.154832] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3246.747551] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d9403fdd000.ost_server_uuid in IDLE state after 0 sec [ 3250.686661] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid 50 [ 3252.008639] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8d9403fdd000.ost_server_uuid in FULL state after 0 sec [ 3253.629846] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 16:14:32 (1775765672) [ 3256.219408] Lustre: DEBUG MARKER: Race attempt 0 [ 3259.041145] Lustre: DEBUG MARKER: Wait for 58423 58451 for 60 sec... [ 3326.703545] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 16:15:45 (1775765745) [ 3335.705626] Lustre: DEBUG MARKER: start test - cycle (0) [ 3363.708668] Lustre: DEBUG MARKER: start test - cycle (1) [ 3388.723403] Lustre: DEBUG MARKER: start test - cycle (2) [ 3413.084299] Lustre: DEBUG MARKER: start test - cycle (3) [ 3438.228857] Lustre: DEBUG MARKER: start test - cycle (4) [ 3445.216256] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: disconnect after 24s idle [ 3445.225920] Lustre: Skipped 3 previous similar messages [ 3466.358916] Lustre: DEBUG MARKER: start test - cycle (5) [ 3489.837536] Lustre: DEBUG MARKER: start test - cycle (6) [ 3515.610515] Lustre: DEBUG MARKER: start test - cycle (7) [ 3542.454915] Lustre: DEBUG MARKER: start test - cycle (8) [ 3567.393978] Lustre: DEBUG MARKER: start test - cycle (9) [ 3593.736323] Lustre: DEBUG MARKER: start test - cycle (10) [ 3627.935877] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 16:20:45 (1775766045) [ 3717.639125] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 16:22:16 (1775766136) [ 3725.469809] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 16:22:24 (1775766144) [ 3733.983415] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 16:22:32 (1775766152) [ 3742.695514] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 16:22:41 (1775766161) [ 3743.217502] Lustre: *** cfs_fail_loc=411, val=0*** [ 3749.926308] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3751.872610] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 16:22:50 (1775766170) [ 3769.214776] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 16:23:08 (1775766188) [ 3787.389273] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 16:23:26 (1775766206) [ 3807.329644] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 16:23:45 (1775766225) [ 3827.385581] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 16:24:05 (1775766245) [ 3843.773611] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 16:24:22 (1775766262) [ 3858.361660] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 16:24:36 (1775766276) [ 3873.150728] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 16:24:51 (1775766291) [ 3887.713295] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 16:25:06 (1775766306) [ 3903.428408] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 16:25:21 (1775766321) [ 3917.748980] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 16:25:36 (1775766336) [ 3931.526632] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 16:25:50 (1775766350) [ 3948.097307] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 16:26:06 (1775766366) [ 3967.457414] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 23s idle [ 3967.460411] Lustre: Skipped 20 previous similar messages [ 5040.138734] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 16:44:19 (1775767459) [ 5054.534146] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 16:44:33 (1775767473) [ 5067.523763] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 16:44:46 (1775767486) [ 5080.652295] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 16:44:59 (1775767499) [ 5094.557570] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 16:45:13 (1775767513) [ 5098.976517] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 23s idle [ 5098.984990] Lustre: Skipped 2 previous similar messages [ 5108.900877] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 16:45:27 (1775767527) [ 5123.500120] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 16:45:42 (1775767542) [ 5137.805800] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 16:45:56 (1775767556) [ 5153.746515] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 16:46:12 (1775767572) [ 5283.656713] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 16:48:22 (1775767702) [ 5298.419079] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 16:48:37 (1775767717) [ 5312.838351] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 16:48:51 (1775767731) [ 5326.557220] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 16:49:05 (1775767745) [ 5340.133473] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 16:49:18 (1775767758) [ 5354.734706] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 16:49:33 (1775767773) [ 5369.506076] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 16:49:48 (1775767788) [ 5384.622913] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 16:50:03 (1775767803) [ 5399.768378] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 16:50:18 (1775767818) [ 5542.870920] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 16:52:41 (1775767961) [ 5815.778198] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 20s idle [ 5815.800189] Lustre: Skipped 8 previous similar messages [ 6486.496911] Lustre: lustre-OST0001-osc-ffff8d9403fdd000: disconnect after 21s idle [ 6486.509160] Lustre: Skipped 11 previous similar messages [ 6878.174173] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 17:14:56 (1775769296) [ 6893.205769] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 17:15:11 (1775769311) [ 6908.660985] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 17:15:27 (1775769327) [ 6923.255704] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 17:15:41 (1775769341) [ 6938.079879] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 17:15:56 (1775769356) [ 6953.451569] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 17:16:12 (1775769372) [ 6968.581670] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 17:16:26 (1775769386) [ 6986.283853] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 17:16:44 (1775769404) [ 7005.254483] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 17:17:03 (1775769423) [ 7022.371777] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 17:17:20 (1775769440) [ 7233.125231] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 17:20:51 (1775769651) [ 7247.812186] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 17:21:06 (1775769666) [ 7261.138657] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 17:21:19 (1775769679) [ 7274.977264] Lustre: lustre-OST0000-osc-ffff8d9408124800: disconnect after 20s idle [ 7274.987651] Lustre: Skipped 12 previous similar messages [ 7275.773667] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 17:21:34 (1775769694) [ 7289.618677] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 17:21:48 (1775769708) [ 7303.454620] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 17:22:02 (1775769722) [ 7316.794369] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 17:22:15 (1775769735) [ 7330.136294] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 17:22:29 (1775769749) [ 7347.062533] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 17:22:45 (1775769765) [ 7889.376250] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 24s idle [ 7889.380979] Lustre: Skipped 11 previous similar messages [ 8602.317372] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 17:43:41 (1775771021) [ 8616.124888] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 17:43:54 (1775771034) [ 8616.418145] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 22s idle [ 8616.427313] Lustre: Skipped 10 previous similar messages [ 8629.667758] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 17:44:08 (1775771048) [ 8643.012923] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 17:44:21 (1775771061) [ 8656.781141] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 17:44:35 (1775771075) [ 8671.110318] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 17:44:49 (1775771089) [ 8685.806671] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 17:45:04 (1775771104) [ 8698.956542] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 17:45:17 (1775771117) [ 8713.269830] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 17:45:32 (1775771132) [ 8726.323271] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 17:45:45 (1775771145) [ 8741.418389] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 17:46:00 (1775771160) [ 8755.779539] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 17:46:14 (1775771174) [ 8769.905239] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 17:46:28 (1775771188) [ 8783.599157] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 17:46:42 (1775771202) [ 8796.998428] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 17:46:55 (1775771215) [ 8809.769392] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 17:47:08 (1775771228) [ 8826.227575] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 17:47:25 (1775771245) [ 8826.582109] LustreError: 6159:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 8828.672166] LustreError: 6159:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 8838.041634] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 17:47:36 (1775771256) [ 8845.870491] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 17:47:44 (1775771264) [ 8846.341462] LustreError: 239828:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8850.400279] LustreError: 239828:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 8850.458608] LustreError: 239828:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8854.544138] LustreError: 239828:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 8854.585955] LustreError: 239834:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8858.648069] LustreError: 239834:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 8864.534611] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 17:48:03 (1775771283) [ 8875.707280] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 17:48:14 (1775771294) [ 8883.345293] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 17:48:22 (1775771302) [ 8891.557964] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 17:48:30 (1775771310) [ 8923.783454] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 17:49:02 (1775771342) [ 8936.099273] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 17:49:15 (1775771355) [ 8948.412851] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 17:49:27 (1775771367) [ 8965.408854] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 17:49:44 (1775771384) [ 8981.054245] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 17:49:59 (1775771399) [ 9001.510257] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 17:50:20 (1775771420) [ 9020.884166] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 17:50:39 (1775771439) [ 9045.443991] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 17:51:04 (1775771464) [ 9069.461582] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 17:51:27 (1775771487) [ 9161.994687] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 17:53:00 (1775771580) [ 9170.006266] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 9175.919988] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 17:53:14 (1775771594) [ 9183.101980] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 17:53:22 (1775771602) [ 9189.801139] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 17:53:28 (1775771608) [ 9195.773388] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 17:53:34 (1775771614) [ 9240.903088] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 17:54:19 (1775771659) [ 9308.143362] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 17:55:27 (1775771727) [ 9316.738765] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 17:55:35 (1775771735) [ 9326.026608] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 17:55:44 (1775771744) [ 9330.214494] LustreError: lustre-MDT0000-mdc-ffff8d9403fdd000: operation ldlm_enqueue to node 192.168.203.104@tcp failed: rc = -35 [ 9330.241398] LustreError: Skipped 1 previous similar message [ 9339.972669] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 17:55:58 (1775771758) [ 9341.364181] LustreError: 2405:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 9343.456238] LustreError: 2405:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 9343.460193] Lustre: lustre-OST0000-osc-ffff8d9403fdd000: disconnect after 20s idle [ 9343.472294] Lustre: Skipped 7 previous similar messages [ 9352.726360] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 17:56:11 (1775771771) [ 9570.526699] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 17:59:49 (1775771989) [ 9581.171582] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 17:59:59 (1775771999) [ 9596.869782] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 18:00:15 (1775772015) [ 9612.889644] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 18:00:31 (1775772031) [ 9629.964845] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 18:00:48 (1775772048) [ 9657.078312] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 18:01:16 (1775772076) [ 9680.847393] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 18:01:39 (1775772099) [ 9693.416290] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 18:01:51 (1775772111) [ 9706.297746] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 18:02:04 (1775772124) [ 9727.184554] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 18:02:26 (1775772146) [ 9783.075811] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 18:03:21 (1775772201) [ 9921.762335] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 18:05:40 (1775772340) [ 9952.743440] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 24s idle [ 9952.756622] Lustre: Skipped 10 previous similar messages [10060.917829] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 18:07:59 (1775772479) [10411.541497] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 18:13:50 (1775772830) [10454.638407] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 18:14:33 (1775772873) [10496.632329] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 18:15:15 (1775772915) [10534.019135] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 18:15:52 (1775772952) [10567.139946] Lustre: lustre-OST0001-osc-ffff8d9408124800: disconnect after 20s idle [10567.145470] Lustre: Skipped 16 previous similar messages [10614.114295] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 18:17:12 (1775773032) [10680.537844] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 18:18:19 (1775773099) [10800.534819] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 18:20:19 (1775773219) [10804.968428] LustreError: 284945:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9408124800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10805.026260] Lustre: Unmounted lustre-client [10809.865073] LustreError: 284958:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9403fdd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10809.873334] LustreError: 284958:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [10809.929315] Lustre: Unmounted lustre-client [10927.767939] Lustre: Mounted lustre-client [10930.371753] Lustre: Mounted lustre-client [10933.749958] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11034.234655] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 18:24:13 (1775773453) [11053.544865] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 18:24:32 (1775773472) [11066.264913] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 18:24:44 (1775773484) [11126.260410] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 18:25:44 (1775773544) [11186.657022] Lustre: lustre-OST0001-osc-ffff8d943d674000: disconnect after 23s idle [11186.673498] Lustre: Skipped 11 previous similar messages [11198.373098] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 18:26:56 (1775773616) [11211.025969] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 18:27:09 (1775773629) [11316.214816] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 18:28:55 (1775773735) [11348.507323] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 18:29:27 (1775773767) [11402.315240] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 18:30:20 (1775773820) [11413.094550] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 18:30:31 (1775773831) [11436.719187] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 18:30:54 (1775773854) [11448.930243] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 18:31:07 (1775773867) [11451.618290] LustreError: 302401:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x25:0x0]: rc = -5 [11451.628225] LustreError: 302401:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11452.592688] LustreError: 302423:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1b5:0x0]: rc = -5 [11452.605013] LustreError: 302423:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [11452.617039] LustreError: 302423:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11452.628372] LustreError: 302423:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 3 previous similar messages [11454.315701] LustreError: 302444:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x31:0x0]: rc = -5 [11454.346031] LustreError: 302444:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [11454.353986] LustreError: 302444:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11454.373645] LustreError: 302444:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 3 previous similar messages [11456.616723] LustreError: 302474:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1c3:0x0]: rc = -5 [11456.644370] LustreError: 302474:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [11456.658739] LustreError: 302474:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11456.670590] LustreError: 302474:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 5 previous similar messages [11457.805926] LustreError: 302493:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff8d9410f59800: inode [0x240000bd0:0x32:0x0] mdc close failed: rc = -2 [11461.116950] LustreError: 302525:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1dd:0x0]: rc = -5 [11461.131158] LustreError: 302525:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [11461.144283] LustreError: 302525:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11461.151720] LustreError: 302525:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 9 previous similar messages [11469.448993] LustreError: 302665:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x5c:0x0]: rc = -5 [11469.460830] LustreError: 302665:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 37 previous similar messages [11469.466894] LustreError: 302665:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11469.479321] LustreError: 302665:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 37 previous similar messages [11485.975283] LustreError: 302978:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x99:0x0]: rc = -5 [11485.997612] LustreError: 302978:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 92 previous similar messages [11486.011191] LustreError: 302978:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11486.029911] LustreError: 302978:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 92 previous similar messages [11515.843235] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 18:32:14 (1775773934) [11523.222536] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 18:32:21 (1775773941) [11782.586553] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 18:36:41 (1775774201) [11784.095223] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [11785.982198] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 18:36:44 (1775774204) [12048.452728] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 18:41:07 (1775774467) [12059.497790] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 18:41:17 (1775774477) [12062.182065] Lustre: lustre-OST0001-osc-ffff8d9410f59800: disconnect after 21s idle [12062.188673] Lustre: Skipped 6 previous similar messages [12188.071897] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 18:43:26 (1775774606) [12201.635976] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 18:43:40 (1775774620) [12214.590766] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 18:43:53 (1775774633) [12406.047823] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 18:47:03 (1775774823) [12595.720141] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 18:50:13 (1775775013) [12603.949592] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 18:50:22 (1775775022) [12622.747285] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 18:50:41 (1775775041) [12623.329677] Lustre: DEBUG MARKER: write [12623.435048] LustreError: 287362:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [12625.391640] Lustre: DEBUG MARKER: kill 331489 [12625.398315] LustreError: 331489:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [12628.448557] LustreError: 287362:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [12631.448123] LustreError: 331489:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [12640.571704] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 18:50:58 (1775775058) [12643.398386] LustreError: 332104:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [12653.496232] LustreError: 332104:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [12663.670582] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 18:51:21 (1775775081) [12664.365162] LustreError: 332692:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [12666.361101] LustreError: 332692:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [12678.172548] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 18:51:36 (1775775096) [12679.932059] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [12682.250638] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 18:51:40 (1775775100) [12690.618822] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 18:51:49 (1775775109) [12698.389976] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 18:51:57 (1775775117) [12709.071522] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 18:52:07 (1775775127) [12721.164413] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 18:52:18 (1775775138) [12731.478460] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 18:52:29 (1775775149) [12739.604691] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 18:52:38 (1775775158) [12750.435435] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 18:52:48 (1775775168) [12763.778915] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 18:53:02 (1775775182) [12765.706698] Lustre: *** cfs_fail_loc=415, val=0*** [12777.412891] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 18:53:16 (1775775196) [12808.515193] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 18:53:47 (1775775227) [12809.207525] LustreError: 287362:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [12809.216686] LustreError: 287362:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [12814.216137] LustreError: 286671:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [12814.220086] LustreError: 286671:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 1 previous similar message [12824.424677] LustreError: 287362:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [12824.439585] LustreError: 287362:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 6 previous similar messages [12831.901136] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 18:54:10 (1775775250) [12840.453543] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 18:54:19 (1775775259) [12848.774113] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 18:54:27 (1775775267) [12856.304196] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 18:54:35 (1775775275) [12865.688226] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 18:54:44 (1775775284) [12881.244702] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 18:54:59 (1775775299) [12882.303835] LustreError: 343440:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [12882.310481] LustreError: 343440:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [12886.368566] LustreError: 343440:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [12886.378425] LustreError: 343440:0:(osc_request.c:2989:osc_build_rpc()) Skipped 1 previous similar message [12894.399400] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 18:55:13 (1775775313) [12899.962655] LustreError: 344150:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943d674000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12899.978450] LustreError: 344150:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [12900.074139] Lustre: Unmounted lustre-client [12903.025820] LustreError: 344171:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410f59800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12903.032677] LustreError: 344171:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [12903.149172] Lustre: Unmounted lustre-client [12904.754101] Lustre: DEBUG MARKER: Iteration 1 [12905.379291] LustreError: 344335:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [12905.400159] LustreError: 344345:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [12905.416664] LustreError: 344335:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4975 [12905.709429] Lustre: Mounted lustre-client [12907.488467] LustreError: 344441:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d941021f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12907.510567] LustreError: 344441:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [12907.638065] Lustre: Unmounted lustre-client [12911.043871] Key type lgssc unregistered [12911.276158] LNet: 344690:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12911.289733] LNetError: 344690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12912.377534] LNet: Removed LNI 192.168.203.4@tcp [12913.440152] Key type .llcrypt unregistered [12913.441342] Key type ._llcrypt unregistered [12914.269381] Key type ._llcrypt registered [12914.270555] Key type .llcrypt registered [12915.251980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12915.273979] alg: No test for adler32 (adler32-zlib) [12916.802827] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [12918.007136] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [12919.912322] Key type lgssc registered [12921.950338] Lustre: Echo OBD driver; http://www.lustre.org/ [12936.287308] Lustre: DEBUG MARKER: Iteration 2 [12936.825629] LustreError: 345525:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [12936.827122] LustreError: 345524:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [12936.838982] LustreError: 345525:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12939.012746] Lustre: Mounted lustre-client [12940.713177] LustreError: 345634:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410957800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12940.852743] Lustre: Unmounted lustre-client [12943.651934] Key type lgssc unregistered [12943.881434] LNet: 345885:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12943.886484] LNetError: 345885:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12943.903052] LNet: Removed LNI 192.168.203.4@tcp [12944.703183] Key type .llcrypt unregistered [12944.707135] Key type ._llcrypt unregistered [12945.751584] Key type ._llcrypt registered [12945.780220] Key type .llcrypt registered [12946.385387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12946.409355] alg: No test for adler32 (adler32-zlib) [12947.605227] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [12947.827146] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [12949.514453] Key type lgssc registered [12950.739299] Lustre: Echo OBD driver; http://www.lustre.org/ [12963.066767] Lustre: DEBUG MARKER: Iteration 3 [12963.455844] LustreError: 346720:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [12963.458169] LustreError: 346719:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [12963.477741] LustreError: 346720:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12964.559913] Lustre: Mounted lustre-client [12966.050300] LustreError: 346825:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94121f5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12966.195545] Lustre: Unmounted lustre-client [12969.492963] Key type lgssc unregistered [12969.883678] LNet: 347073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12969.903919] LNetError: 347073:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12969.924633] LNet: Removed LNI 192.168.203.4@tcp [12970.760374] Key type .llcrypt unregistered [12970.762536] Key type ._llcrypt unregistered [12972.071358] Key type ._llcrypt registered [12972.123224] Key type .llcrypt registered [12972.294588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12972.313066] alg: No test for adler32 (adler32-zlib) [12973.430219] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [12973.710904] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [12975.472147] Key type lgssc registered [12976.896532] Lustre: Echo OBD driver; http://www.lustre.org/ [12991.048434] Lustre: DEBUG MARKER: Iteration 4 [12991.675680] LustreError: 347907:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [12991.679621] LustreError: 347908:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [12991.701379] LustreError: 347907:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12992.817235] Lustre: Mounted lustre-client [12994.579902] LustreError: 348016:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d941021b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12994.676939] Lustre: Unmounted lustre-client [12998.122814] Key type lgssc unregistered [12998.405313] LNet: 348267:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12998.419042] LNetError: 348267:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12998.439741] LNet: Removed LNI 192.168.203.4@tcp [12999.301267] Key type .llcrypt unregistered [12999.308562] Key type ._llcrypt unregistered [13000.269265] Key type ._llcrypt registered [13000.282764] Key type .llcrypt registered [13000.890417] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13000.914596] alg: No test for adler32 (adler32-zlib) [13002.145632] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13002.475934] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13004.200174] Key type lgssc registered [13005.721789] Lustre: Echo OBD driver; http://www.lustre.org/ [13019.342576] Lustre: DEBUG MARKER: Iteration 5 [13019.838166] LustreError: 349100:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13019.848932] LustreError: 349101:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13019.862557] LustreError: 349100:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [13020.918487] Lustre: Mounted lustre-client [13022.612828] LustreError: 349204:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410957800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13022.698523] Lustre: Unmounted lustre-client [13026.145476] Key type lgssc unregistered [13026.686061] LNet: 349455:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13026.695639] LNetError: 349455:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13026.719342] LNet: Removed LNI 192.168.203.4@tcp [13027.852161] Key type .llcrypt unregistered [13027.856104] Key type ._llcrypt unregistered [13029.333892] Key type ._llcrypt registered [13029.347730] Key type .llcrypt registered [13030.129025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13030.152659] alg: No test for adler32 (adler32-zlib) [13031.457419] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13031.857097] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13033.640263] Key type lgssc registered [13035.314173] Lustre: Echo OBD driver; http://www.lustre.org/ [13048.880976] Lustre: DEBUG MARKER: Iteration 6 [13049.396561] LustreError: 350289:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13049.397779] LustreError: 350290:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13049.424593] LustreError: 350289:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [13051.530264] Lustre: Mounted lustre-client [13051.540423] Lustre: Skipped 1 previous similar message [13053.518468] LustreError: 350397:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d940864b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13053.685201] Lustre: Unmounted lustre-client [13056.941855] Key type lgssc unregistered [13057.197188] LNet: 350646:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13057.208279] LNetError: 350646:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13057.248387] LNet: Removed LNI 192.168.203.4@tcp [13058.068737] Key type .llcrypt unregistered [13058.081789] Key type ._llcrypt unregistered [13058.963524] Key type ._llcrypt registered [13058.969208] Key type .llcrypt registered [13059.441823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13059.472314] alg: No test for adler32 (adler32-zlib) [13060.660960] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13060.940503] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13062.656910] Key type lgssc registered [13064.019492] Lustre: Echo OBD driver; http://www.lustre.org/ [13077.095270] Lustre: DEBUG MARKER: Iteration 7 [13077.362197] LustreError: 351477:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13077.368187] LustreError: 351479:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13077.377578] LustreError: 351477:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [13078.473748] Lustre: Mounted lustre-client [13080.039881] LustreError: 351588:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943d675000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13080.133419] Lustre: Unmounted lustre-client [13083.033432] Key type lgssc unregistered [13083.393525] LNet: 351838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13083.411654] LNetError: 351838:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13083.426470] LNet: Removed LNI 192.168.203.4@tcp [13084.109634] Key type .llcrypt unregistered [13084.114935] Key type ._llcrypt unregistered [13085.404448] Key type ._llcrypt registered [13085.424448] Key type .llcrypt registered [13085.644147] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13085.663787] alg: No test for adler32 (adler32-zlib) [13086.862920] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13087.051436] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13088.713089] Key type lgssc registered [13090.013881] Lustre: Echo OBD driver; http://www.lustre.org/ [13104.892186] Lustre: DEBUG MARKER: Iteration 8 [13105.292539] LustreError: 352673:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13105.300318] LustreError: 352672:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13105.313721] LustreError: 352673:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [13106.398855] Lustre: Mounted lustre-client [13107.777703] LustreError: 352781:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410814800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13107.861645] Lustre: Unmounted lustre-client [13110.595631] Key type lgssc unregistered [13110.864216] LNet: 353031:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13110.869517] LNetError: 353031:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13110.884706] LNet: Removed LNI 192.168.203.4@tcp [13111.561523] Key type .llcrypt unregistered [13111.564343] Key type ._llcrypt unregistered [13112.206606] Key type ._llcrypt registered [13112.211831] Key type .llcrypt registered [13112.797267] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13112.814914] alg: No test for adler32 (adler32-zlib) [13113.905331] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13114.123695] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13115.768243] Key type lgssc registered [13116.997901] Lustre: Echo OBD driver; http://www.lustre.org/ [13130.186836] Lustre: DEBUG MARKER: Iteration 9 [13130.513106] LustreError: 353865:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13130.514617] LustreError: 353866:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13130.532660] LustreError: 353865:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [13132.722977] Lustre: Mounted lustre-client [13134.293759] LustreError: 353975:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94050bc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13134.423299] Lustre: Unmounted lustre-client [13138.015236] Key type lgssc unregistered [13138.298843] LNet: 354222:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13138.332514] LNetError: 354222:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13138.356905] LNet: Removed LNI 192.168.203.4@tcp [13139.210185] Key type .llcrypt unregistered [13139.211599] Key type ._llcrypt unregistered [13140.575738] Key type ._llcrypt registered [13140.596742] Key type .llcrypt registered [13141.280214] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13141.307907] alg: No test for adler32 (adler32-zlib) [13142.531987] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13142.804072] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13144.544582] Key type lgssc registered [13146.748568] Lustre: Echo OBD driver; http://www.lustre.org/ [13163.084832] Lustre: DEBUG MARKER: Iteration 10 [13163.493415] LustreError: 355058:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13163.500145] LustreError: 355057:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13163.523851] LustreError: 355058:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13164.719546] Lustre: Mounted lustre-client [13164.731189] Lustre: Skipped 1 previous similar message [13166.373618] LustreError: 355161:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943f674800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13166.462316] Lustre: Unmounted lustre-client [13169.328934] Key type lgssc unregistered [13169.617615] LNet: 355412:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13169.627975] LNetError: 355412:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13169.657438] LNet: Removed LNI 192.168.203.4@tcp [13170.342336] Key type .llcrypt unregistered [13170.345683] Key type ._llcrypt unregistered [13171.137846] Key type ._llcrypt registered [13171.151356] Key type .llcrypt registered [13171.647203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13171.657215] alg: No test for adler32 (adler32-zlib) [13172.717583] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13172.951514] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13174.664254] Key type lgssc registered [13175.874161] Lustre: Echo OBD driver; http://www.lustre.org/ [13187.041093] Lustre: DEBUG MARKER: Iteration 11 [13187.491273] LustreError: 356248:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13187.491813] LustreError: 356249:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13187.504153] LustreError: 356248:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13188.570548] Lustre: Mounted lustre-client [13190.054907] LustreError: 356366:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94056cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13190.159473] Lustre: Unmounted lustre-client [13192.894698] Key type lgssc unregistered [13193.313423] LNet: 356611:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13193.328592] LNetError: 356611:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13193.348762] LNet: Removed LNI 192.168.203.4@tcp [13194.247414] Key type .llcrypt unregistered [13194.252748] Key type ._llcrypt unregistered [13195.050393] Key type ._llcrypt registered [13195.054672] Key type .llcrypt registered [13195.585055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13195.606452] alg: No test for adler32 (adler32-zlib) [13196.859711] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13197.132260] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13198.880465] Key type lgssc registered [13200.155283] Lustre: Echo OBD driver; http://www.lustre.org/ [13212.324460] Lustre: DEBUG MARKER: Iteration 12 [13212.799707] LustreError: 357445:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13212.818970] LustreError: 357451:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13212.832039] LustreError: 357445:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [13213.923989] Lustre: Mounted lustre-client [13215.249481] LustreError: 357554:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9402ed3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13215.352989] Lustre: Unmounted lustre-client [13218.110799] Key type lgssc unregistered [13218.395713] LNet: 357805:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13218.403434] LNetError: 357805:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13218.426089] LNet: Removed LNI 192.168.203.4@tcp [13219.069750] Key type .llcrypt unregistered [13219.071974] Key type ._llcrypt unregistered [13219.910996] Key type ._llcrypt registered [13219.942624] Key type .llcrypt registered [13220.227332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13220.247343] alg: No test for adler32 (adler32-zlib) [13221.310423] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13221.572337] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13223.232847] Key type lgssc registered [13224.310863] Lustre: Echo OBD driver; http://www.lustre.org/ [13236.562445] Lustre: DEBUG MARKER: Iteration 13 [13237.150567] LustreError: 358640:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13237.152107] LustreError: 358639:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13237.180421] LustreError: 358640:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4978 [13238.320340] Lustre: Mounted lustre-client [13239.845432] LustreError: 358745:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d940864d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13239.945092] Lustre: Unmounted lustre-client [13242.922061] Key type lgssc unregistered [13243.244428] LNet: 359006:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13243.264994] LNetError: 359006:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13243.292670] LNet: Removed LNI 192.168.203.4@tcp [13244.144201] Key type .llcrypt unregistered [13244.149185] Key type ._llcrypt unregistered [13244.875308] Key type ._llcrypt registered [13244.877223] Key type .llcrypt registered [13245.357906] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13245.437347] alg: No test for adler32 (adler32-zlib) [13246.552236] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13246.930433] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13248.680202] Key type lgssc registered [13250.717382] Lustre: Echo OBD driver; http://www.lustre.org/ [13265.383725] Lustre: DEBUG MARKER: Iteration 14 [13265.872681] LustreError: 359839:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13265.876063] LustreError: 359840:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13265.881892] LustreError: 359839:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [13268.110722] Lustre: Mounted lustre-client [13271.133962] LustreError: 359956:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943d677000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13271.305529] Lustre: Unmounted lustre-client [13274.843475] Key type lgssc unregistered [13275.088229] LNet: 360203:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13275.093206] LNetError: 360203:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13275.114929] LNet: Removed LNI 192.168.203.4@tcp [13275.643206] Key type .llcrypt unregistered [13275.647048] Key type ._llcrypt unregistered [13276.184568] Key type ._llcrypt registered [13276.187641] Key type .llcrypt registered [13277.064777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13277.109077] alg: No test for adler32 (adler32-zlib) [13278.224811] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13278.536708] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13280.272647] Key type lgssc registered [13281.840761] Lustre: Echo OBD driver; http://www.lustre.org/ [13296.090880] Lustre: DEBUG MARKER: Iteration 15 [13296.498923] LustreError: 361036:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13296.502294] LustreError: 361035:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13296.524408] LustreError: 361036:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [13297.704742] Lustre: Mounted lustre-client [13299.502659] LustreError: 361146:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943cb9f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13299.660341] Lustre: Unmounted lustre-client [13302.728738] Key type lgssc unregistered [13302.975112] LNet: 361397:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13302.980876] LNetError: 361397:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13304.039897] LNet: Removed LNI 192.168.203.4@tcp [13304.924137] Key type .llcrypt unregistered [13304.926138] Key type ._llcrypt unregistered [13305.966284] Key type ._llcrypt registered [13305.967881] Key type .llcrypt registered [13306.496606] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13306.519820] alg: No test for adler32 (adler32-zlib) [13307.761124] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13308.048637] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13309.776356] Key type lgssc registered [13311.347621] Lustre: Echo OBD driver; http://www.lustre.org/ [13324.291272] Lustre: DEBUG MARKER: Iteration 16 [13324.660664] LustreError: 362231:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13324.667583] LustreError: 362232:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13324.683200] LustreError: 362231:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [13325.731652] Lustre: Mounted lustre-client [13327.151484] LustreError: 362337:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410dd8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13327.297850] Lustre: Unmounted lustre-client [13330.013492] Key type lgssc unregistered [13330.327574] LNet: 362588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13330.343386] LNetError: 362588:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13330.365873] LNet: Removed LNI 192.168.203.4@tcp [13331.048227] Key type .llcrypt unregistered [13331.052234] Key type ._llcrypt unregistered [13332.283338] Key type ._llcrypt registered [13332.314971] Key type .llcrypt registered [13332.552545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13332.569484] alg: No test for adler32 (adler32-zlib) [13333.696628] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13334.010481] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13335.752173] Key type lgssc registered [13337.139963] Lustre: Echo OBD driver; http://www.lustre.org/ [13349.830861] Lustre: DEBUG MARKER: Iteration 17 [13350.285804] LustreError: 363419:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13350.299557] LustreError: 363423:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13350.304717] LustreError: 363419:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [13352.507753] Lustre: Mounted lustre-client [13354.189527] LustreError: 363529:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9426e29000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13354.353827] Lustre: Unmounted lustre-client [13357.515013] Key type lgssc unregistered [13357.958689] LNet: 363780:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13357.970897] LNetError: 363780:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13357.990221] LNet: Removed LNI 192.168.203.4@tcp [13359.033196] Key type .llcrypt unregistered [13359.034582] Key type ._llcrypt unregistered [13359.438355] Key type ._llcrypt registered [13359.440444] Key type .llcrypt registered [13359.857684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13359.878855] alg: No test for adler32 (adler32-zlib) [13360.966118] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13361.254686] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13362.985694] Key type lgssc registered [13364.128388] Lustre: Echo OBD driver; http://www.lustre.org/ [13379.390729] Lustre: DEBUG MARKER: Iteration 18 [13379.763540] LustreError: 364613:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13379.763584] LustreError: 364615:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13379.779805] LustreError: 364613:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [13382.054740] Lustre: Mounted lustre-client [13384.410096] LustreError: 364724:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d941f011800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13384.512710] Lustre: Unmounted lustre-client [13387.096605] Key type lgssc unregistered [13387.320738] LNet: 364975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13387.325890] LNetError: 364975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13387.343578] LNet: Removed LNI 192.168.203.4@tcp [13388.038495] Key type .llcrypt unregistered [13388.045624] Key type ._llcrypt unregistered [13389.031145] Key type ._llcrypt registered [13389.084030] Key type .llcrypt registered [13389.492693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13389.508762] alg: No test for adler32 (adler32-zlib) [13390.587579] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13390.800851] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13392.512213] Key type lgssc registered [13393.901120] Lustre: Echo OBD driver; http://www.lustre.org/ [13405.512414] Lustre: DEBUG MARKER: Iteration 19 [13405.849308] LustreError: 365807:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13405.851508] LustreError: 365809:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13405.874231] LustreError: 365807:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [13406.966590] Lustre: Mounted lustre-client [13406.976583] Lustre: Skipped 1 previous similar message [13408.503549] LustreError: 365919:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9431df0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13408.623608] Lustre: Unmounted lustre-client [13411.947652] Key type lgssc unregistered [13412.242659] LNet: 366164:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13412.267307] LNetError: 366164:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13412.297080] LNet: Removed LNI 192.168.203.4@tcp [13413.014447] Key type .llcrypt unregistered [13413.018292] Key type ._llcrypt unregistered [13414.187387] Key type ._llcrypt registered [13414.198713] Key type .llcrypt registered [13414.529813] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13414.542093] alg: No test for adler32 (adler32-zlib) [13415.694864] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13415.968445] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13417.640405] Key type lgssc registered [13419.215167] Lustre: Echo OBD driver; http://www.lustre.org/ [13431.250745] Lustre: DEBUG MARKER: Iteration 20 [13431.695939] LustreError: 366996:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13431.699832] LustreError: 366998:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13431.723853] LustreError: 366996:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [13432.910889] Lustre: Mounted lustre-client [13434.369853] LustreError: 367107:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9431df3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13434.459149] Lustre: Unmounted lustre-client [13437.232142] Key type lgssc unregistered [13437.435983] LNet: 367357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13437.443122] LNetError: 367357:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13437.479157] LNet: Removed LNI 192.168.203.4@tcp [13438.324156] Key type .llcrypt unregistered [13438.327745] Key type ._llcrypt unregistered [13439.333617] Key type ._llcrypt registered [13439.335836] Key type .llcrypt registered [13439.728549] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13439.745445] alg: No test for adler32 (adler32-zlib) [13440.743565] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13440.998385] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13442.672233] Key type lgssc registered [13444.130030] Lustre: Echo OBD driver; http://www.lustre.org/ [13458.536089] Lustre: DEBUG MARKER: Iteration 21 [13459.103213] LustreError: 368188:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13459.117586] LustreError: 368196:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13459.133612] LustreError: 368188:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [13460.236652] Lustre: Mounted lustre-client [13461.849276] LustreError: 368305:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94129fc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13461.972138] Lustre: Unmounted lustre-client [13465.228877] Key type lgssc unregistered [13465.515585] LNet: 368549:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13465.520361] LNetError: 368549:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13465.551712] LNet: Removed LNI 192.168.203.4@tcp [13466.237163] Key type .llcrypt unregistered [13466.243440] Key type ._llcrypt unregistered [13467.254763] Key type ._llcrypt registered [13467.256457] Key type .llcrypt registered [13467.719776] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13467.738718] alg: No test for adler32 (adler32-zlib) [13468.854565] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13469.117444] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13470.800246] Key type lgssc registered [13472.040255] Lustre: Echo OBD driver; http://www.lustre.org/ [13485.393181] Lustre: DEBUG MARKER: Iteration 22 [13485.758599] LustreError: 369383:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13485.767119] LustreError: 369384:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13485.784419] LustreError: 369383:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [13486.937191] Lustre: Mounted lustre-client [13488.343327] LustreError: 369493:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9427763800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13488.468219] Lustre: Unmounted lustre-client [13491.751591] Key type lgssc unregistered [13492.004407] LNet: 369743:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13492.015014] LNetError: 369743:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13492.028727] LNet: Removed LNI 192.168.203.4@tcp [13492.628504] Key type .llcrypt unregistered [13492.636000] Key type ._llcrypt unregistered [13493.424225] Key type ._llcrypt registered [13493.426403] Key type .llcrypt registered [13493.957970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13493.970936] alg: No test for adler32 (adler32-zlib) [13495.140449] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13495.354748] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13497.008129] Key type lgssc registered [13498.323035] Lustre: Echo OBD driver; http://www.lustre.org/ [13510.953788] Lustre: DEBUG MARKER: Iteration 23 [13511.556160] LustreError: 370579:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13511.561787] LustreError: 370578:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13511.568883] LustreError: 370579:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13513.719851] Lustre: Mounted lustre-client [13515.315893] LustreError: 370686:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410ddd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13515.437802] Lustre: Unmounted lustre-client [13518.100776] Key type lgssc unregistered [13518.367157] LNet: 370936:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13518.372737] LNetError: 370936:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13518.384286] LNet: Removed LNI 192.168.203.4@tcp [13519.006276] Key type .llcrypt unregistered [13519.007930] Key type ._llcrypt unregistered [13520.043647] Key type ._llcrypt registered [13520.056065] Key type .llcrypt registered [13520.345285] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13520.366615] alg: No test for adler32 (adler32-zlib) [13521.528269] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13521.783836] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13523.520240] Key type lgssc registered [13524.515487] Lustre: Echo OBD driver; http://www.lustre.org/ [13536.341798] Lustre: DEBUG MARKER: Iteration 24 [13536.770377] LustreError: 371770:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13536.770614] LustreError: 371771:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13536.787279] LustreError: 371770:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [13537.865454] Lustre: Mounted lustre-client [13539.459747] LustreError: 371880:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410272000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13539.583264] Lustre: Unmounted lustre-client [13542.647691] Key type lgssc unregistered [13542.905781] LNet: 372130:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13542.915408] LNetError: 372130:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13542.941077] LNet: Removed LNI 192.168.203.4@tcp [13543.537142] Key type .llcrypt unregistered [13543.539315] Key type ._llcrypt unregistered [13544.587101] Key type ._llcrypt registered [13544.627065] Key type .llcrypt registered [13544.943148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13544.961877] alg: No test for adler32 (adler32-zlib) [13546.036761] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13546.210805] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13547.992682] Key type lgssc registered [13549.377057] Lustre: Echo OBD driver; http://www.lustre.org/ [13566.663817] Lustre: DEBUG MARKER: Iteration 25 [13567.167138] LustreError: 372965:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13567.170028] LustreError: 372966:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13567.195655] LustreError: 372965:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4976 [13568.472547] Lustre: Mounted lustre-client [13570.625432] LustreError: 373074:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9426e2c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13570.863365] Lustre: Unmounted lustre-client [13574.541927] Key type lgssc unregistered [13574.995645] LNet: 373324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13575.001499] LNetError: 373324:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13575.031807] LNet: Removed LNI 192.168.203.4@tcp [13575.972228] Key type .llcrypt unregistered [13575.974071] Key type ._llcrypt unregistered [13577.089610] Key type ._llcrypt registered [13577.091935] Key type .llcrypt registered [13577.247256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13577.255855] alg: No test for adler32 (adler32-zlib) [13578.378179] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13578.550814] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13580.192157] Key type lgssc registered [13581.078459] Lustre: Echo OBD driver; http://www.lustre.org/ [13594.161971] Lustre: DEBUG MARKER: Iteration 26 [13594.524491] LustreError: 374157:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13594.524826] LustreError: 374159:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13594.544582] LustreError: 374157:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13595.714799] Lustre: Mounted lustre-client [13595.727673] Lustre: Skipped 1 previous similar message [13597.223061] LustreError: 374268:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9406ad0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13597.363206] Lustre: Unmounted lustre-client [13600.267970] Key type lgssc unregistered [13600.529173] LNet: 374517:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13600.534302] LNetError: 374517:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13600.550275] LNet: Removed LNI 192.168.203.4@tcp [13601.248219] Key type .llcrypt unregistered [13601.251419] Key type ._llcrypt unregistered [13602.291437] Key type ._llcrypt registered [13602.292823] Key type .llcrypt registered [13602.513811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13602.523504] alg: No test for adler32 (adler32-zlib) [13603.541815] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13603.867531] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13605.568230] Key type lgssc registered [13606.939772] Lustre: Echo OBD driver; http://www.lustre.org/ [13618.072330] Lustre: DEBUG MARKER: Iteration 27 [13618.338636] LustreError: 375352:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13618.339122] LustreError: 375353:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13618.354857] LustreError: 375352:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [13620.420641] Lustre: Mounted lustre-client [13621.969293] LustreError: 375460:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9409cb0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13622.129321] Lustre: Unmounted lustre-client [13625.233323] Key type lgssc unregistered [13625.491993] LNet: 375712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13625.509383] LNetError: 375712:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13625.533055] LNet: Removed LNI 192.168.203.4@tcp [13626.225904] Key type .llcrypt unregistered [13626.227573] Key type ._llcrypt unregistered [13627.102743] Key type ._llcrypt registered [13627.107444] Key type .llcrypt registered [13627.452937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13627.474813] alg: No test for adler32 (adler32-zlib) [13628.564484] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13628.787524] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13630.504178] Key type lgssc registered [13631.803198] Lustre: Echo OBD driver; http://www.lustre.org/ [13643.754937] Lustre: DEBUG MARKER: Iteration 28 [13644.183970] LustreError: 376546:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13644.188553] LustreError: 376553:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13644.203407] LustreError: 376546:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [13645.255370] Lustre: Mounted lustre-client [13646.706863] LustreError: 376656:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9406ad5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13646.874295] Lustre: Unmounted lustre-client [13649.467639] Key type lgssc unregistered [13649.775474] LNet: 376907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13649.788787] LNetError: 376907:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13649.806397] LNet: Removed LNI 192.168.203.4@tcp [13650.478157] Key type .llcrypt unregistered [13650.480630] Key type ._llcrypt unregistered [13651.889289] Key type ._llcrypt registered [13651.898946] Key type .llcrypt registered [13652.078255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13652.095499] alg: No test for adler32 (adler32-zlib) [13653.184661] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13653.456574] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13655.144216] Key type lgssc registered [13656.745821] Lustre: Echo OBD driver; http://www.lustre.org/ [13669.245977] Lustre: DEBUG MARKER: Iteration 29 [13669.528417] LustreError: 377739:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13669.528595] LustreError: 377742:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13669.553124] LustreError: 377739:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [13670.617104] Lustre: Mounted lustre-client [13670.625659] Lustre: Skipped 1 previous similar message [13671.906193] LustreError: 377843:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9431bd7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13672.050122] Lustre: Unmounted lustre-client [13674.347816] Key type lgssc unregistered [13674.595718] LNet: 378092:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13674.601881] LNetError: 378092:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13674.615193] LNet: Removed LNI 192.168.203.4@tcp [13675.142172] Key type .llcrypt unregistered [13675.146815] Key type ._llcrypt unregistered [13675.924636] Key type ._llcrypt registered [13675.927371] Key type .llcrypt registered [13676.336108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13676.350811] alg: No test for adler32 (adler32-zlib) [13677.414353] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13677.594764] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13679.282142] Key type lgssc registered [13680.647649] Lustre: Echo OBD driver; http://www.lustre.org/ [13692.753942] Lustre: DEBUG MARKER: Iteration 30 [13693.152743] LustreError: 378925:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13693.153434] LustreError: 378926:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13693.175585] LustreError: 378925:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [13694.285704] Lustre: Mounted lustre-client [13695.900666] LustreError: 379033:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9408122000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13696.008058] Lustre: Unmounted lustre-client [13699.003248] Key type lgssc unregistered [13699.327897] LNet: 379283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13699.339134] LNetError: 379283:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13699.367541] LNet: Removed LNI 192.168.203.4@tcp [13700.181174] Key type .llcrypt unregistered [13700.188859] Key type ._llcrypt unregistered [13701.099980] Key type ._llcrypt registered [13701.109313] Key type .llcrypt registered [13701.777710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13701.795939] alg: No test for adler32 (adler32-zlib) [13703.006679] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13703.348787] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13705.121167] Key type lgssc registered [13706.426562] Lustre: Echo OBD driver; http://www.lustre.org/ [13718.915957] Lustre: DEBUG MARKER: Iteration 31 [13719.347263] LustreError: 380117:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13719.355138] LustreError: 380118:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13719.373200] LustreError: 380117:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [13720.484634] Lustre: Mounted lustre-client [13722.025657] LustreError: 380223:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9435993000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13722.154210] Lustre: Unmounted lustre-client [13724.734437] Key type lgssc unregistered [13724.995308] LNet: 380474:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13725.003065] LNetError: 380474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13725.039923] LNet: Removed LNI 192.168.203.4@tcp [13725.715169] Key type .llcrypt unregistered [13725.720231] Key type ._llcrypt unregistered [13726.477913] Key type ._llcrypt registered [13726.483517] Key type .llcrypt registered [13726.975759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13726.991678] alg: No test for adler32 (adler32-zlib) [13728.086218] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13728.352368] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13730.049165] Key type lgssc registered [13731.318541] Lustre: Echo OBD driver; http://www.lustre.org/ [13743.101441] Lustre: DEBUG MARKER: Iteration 32 [13743.448492] LustreError: 381307:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13743.453334] LustreError: 381309:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13743.477064] LustreError: 381307:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [13744.608312] Lustre: Mounted lustre-client [13744.616173] Lustre: Skipped 1 previous similar message [13746.467288] LustreError: 381418:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9409207800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13746.598552] Lustre: Unmounted lustre-client [13749.587387] Key type lgssc unregistered [13749.904127] LNet: 381669:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13749.908301] LNetError: 381669:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13749.927337] LNet: Removed LNI 192.168.203.4@tcp [13750.590416] Key type .llcrypt unregistered [13750.593200] Key type ._llcrypt unregistered [13751.360270] Key type ._llcrypt registered [13751.363863] Key type .llcrypt registered [13751.599475] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13751.621168] alg: No test for adler32 (adler32-zlib) [13752.743715] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13752.995090] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13754.680231] Key type lgssc registered [13756.312804] Lustre: Echo OBD driver; http://www.lustre.org/ [13767.824195] Lustre: DEBUG MARKER: Iteration 33 [13768.175812] LustreError: 382501:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13768.178076] LustreError: 382503:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13768.192439] LustreError: 382501:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [13769.227119] Lustre: Mounted lustre-client [13770.955824] LustreError: 382619:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410ddb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13771.050420] Lustre: Unmounted lustre-client [13773.632093] Key type lgssc unregistered [13773.909624] LNet: 382861:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13773.924328] LNetError: 382861:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13773.941481] LNet: Removed LNI 192.168.203.4@tcp [13774.469307] Key type .llcrypt unregistered [13774.478749] Key type ._llcrypt unregistered [13775.228333] Key type ._llcrypt registered [13775.238458] Key type .llcrypt registered [13775.760861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13775.782074] alg: No test for adler32 (adler32-zlib) [13776.907950] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13777.224927] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13778.904162] Key type lgssc registered [13780.104678] Lustre: Echo OBD driver; http://www.lustre.org/ [13793.480355] Lustre: DEBUG MARKER: Iteration 34 [13794.091513] LustreError: 383693:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13794.092490] LustreError: 383694:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13794.122647] LustreError: 383693:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [13795.252132] Lustre: Mounted lustre-client [13796.705217] LustreError: 383802:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9435997800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13796.792443] Lustre: Unmounted lustre-client [13800.315250] Key type lgssc unregistered [13800.714395] LNet: 384050:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13800.733585] LNetError: 384050:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13801.781559] LNet: Removed LNI 192.168.203.4@tcp [13802.739266] Key type .llcrypt unregistered [13802.743602] Key type ._llcrypt unregistered [13803.806679] Key type ._llcrypt registered [13803.820919] Key type .llcrypt registered [13804.394761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13804.417638] alg: No test for adler32 (adler32-zlib) [13805.549484] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13805.922569] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13807.632923] Key type lgssc registered [13808.965778] Lustre: Echo OBD driver; http://www.lustre.org/ [13822.467387] Lustre: DEBUG MARKER: Iteration 35 [13823.105121] LustreError: 384885:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13823.106178] LustreError: 384890:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13823.121033] LustreError: 384885:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [13824.222804] Lustre: Mounted lustre-client [13825.616314] LustreError: 384994:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9435996800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13825.712118] Lustre: Unmounted lustre-client [13828.329971] Key type lgssc unregistered [13828.636657] LNet: 385244:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13828.643212] LNetError: 385244:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13828.666499] LNet: Removed LNI 192.168.203.4@tcp [13829.301183] Key type .llcrypt unregistered [13829.303552] Key type ._llcrypt unregistered [13830.328610] Key type ._llcrypt registered [13830.337609] Key type .llcrypt registered [13830.603055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13830.620478] alg: No test for adler32 (adler32-zlib) [13831.765364] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13831.949889] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13833.632193] Key type lgssc registered [13834.682327] Lustre: Echo OBD driver; http://www.lustre.org/ [13846.092777] Lustre: DEBUG MARKER: Iteration 36 [13846.369983] LustreError: 386078:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13846.372427] LustreError: 386079:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13846.394321] LustreError: 386078:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13847.337668] Lustre: Mounted lustre-client [13847.344829] Lustre: Skipped 1 previous similar message [13849.093047] LustreError: 386185:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410ddd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13849.201761] Lustre: Unmounted lustre-client [13851.700977] Key type lgssc unregistered [13851.972952] LNet: 386436:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13851.982793] LNetError: 386436:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13852.019968] LNet: Removed LNI 192.168.203.4@tcp [13852.728682] Key type .llcrypt unregistered [13852.730862] Key type ._llcrypt unregistered [13853.521947] Key type ._llcrypt registered [13853.528387] Key type .llcrypt registered [13853.874273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13853.882213] alg: No test for adler32 (adler32-zlib) [13854.939854] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13855.129476] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13856.872174] Key type lgssc registered [13858.488293] Lustre: Echo OBD driver; http://www.lustre.org/ [13873.764782] Lustre: DEBUG MARKER: Iteration 37 [13874.313083] LustreError: 387270:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13874.317894] LustreError: 387269:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13874.362266] LustreError: 387270:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4958 [13875.612382] Lustre: Mounted lustre-client [13875.626245] Lustre: Skipped 1 previous similar message [13877.759221] LustreError: 387383:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9408123800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13877.926617] Lustre: Unmounted lustre-client [13880.510188] Key type lgssc unregistered [13880.728546] LNet: 387630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13880.732569] LNetError: 387630:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13880.750253] LNet: Removed LNI 192.168.203.4@tcp [13881.386276] Key type .llcrypt unregistered [13881.389061] Key type ._llcrypt unregistered [13882.106506] Key type ._llcrypt registered [13882.109322] Key type .llcrypt registered [13882.709566] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13882.726863] alg: No test for adler32 (adler32-zlib) [13883.836459] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13884.143093] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13885.920529] Key type lgssc registered [13887.014639] Lustre: Echo OBD driver; http://www.lustre.org/ [13900.266158] Lustre: DEBUG MARKER: Iteration 38 [13900.540133] LustreError: 388462:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13900.540884] LustreError: 388463:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13900.562486] LustreError: 388462:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [13901.536996] Lustre: Mounted lustre-client [13903.082190] LustreError: 388569:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d942d06b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13903.173587] Lustre: Unmounted lustre-client [13905.654849] Key type lgssc unregistered [13905.924933] LNet: 388821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13905.939231] LNetError: 388821:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13905.965647] LNet: Removed LNI 192.168.203.4@tcp [13906.883266] Key type .llcrypt unregistered [13906.886149] Key type ._llcrypt unregistered [13908.115482] Key type ._llcrypt registered [13908.119303] Key type .llcrypt registered [13908.348970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13908.370600] alg: No test for adler32 (adler32-zlib) [13909.431931] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13909.637814] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13911.328348] Key type lgssc registered [13912.638676] Lustre: Echo OBD driver; http://www.lustre.org/ [13925.005435] Lustre: DEBUG MARKER: Iteration 39 [13925.444909] LustreError: 389656:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13925.449809] LustreError: 389657:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13925.459166] LustreError: 389656:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [13926.500564] Lustre: Mounted lustre-client [13928.034986] LustreError: 389769:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9426e2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13928.162073] Lustre: Unmounted lustre-client [13931.048877] Key type lgssc unregistered [13931.357396] LNet: 390014:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13931.367767] LNetError: 390014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13931.402357] LNet: Removed LNI 192.168.203.4@tcp [13932.131141] Key type .llcrypt unregistered [13932.133413] Key type ._llcrypt unregistered [13932.900562] Key type ._llcrypt registered [13932.912528] Key type .llcrypt registered [13933.194210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13933.213847] alg: No test for adler32 (adler32-zlib) [13934.386759] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13934.724676] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13936.480252] Key type lgssc registered [13937.710531] Lustre: Echo OBD driver; http://www.lustre.org/ [13949.095658] Lustre: DEBUG MARKER: Iteration 40 [13949.752516] LustreError: 390847:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13949.757848] LustreError: 390848:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13949.775576] LustreError: 390847:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [13950.995662] Lustre: Mounted lustre-client [13951.004880] Lustre: Skipped 1 previous similar message [13952.632794] LustreError: 390955:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9406ad3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13952.746985] Lustre: Unmounted lustre-client [13956.199466] Key type lgssc unregistered [13956.507217] LNet: 391207:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13956.512424] LNetError: 391207:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13956.540604] LNet: Removed LNI 192.168.203.4@tcp [13957.128319] Key type .llcrypt unregistered [13957.130661] Key type ._llcrypt unregistered [13957.996074] Key type ._llcrypt registered [13958.099992] Key type .llcrypt registered [13958.424319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13958.439629] alg: No test for adler32 (adler32-zlib) [13959.680555] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13959.911623] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13961.664430] Key type lgssc registered [13963.477300] Lustre: Echo OBD driver; http://www.lustre.org/ [13980.892845] Lustre: DEBUG MARKER: Iteration 41 [13981.288674] LustreError: 392038:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [13981.316697] LustreError: 392058:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [13981.323101] LustreError: 392038:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4966 [13983.405855] Lustre: Mounted lustre-client [13985.263641] LustreError: 392154:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94192f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13985.359413] Lustre: Unmounted lustre-client [13988.941336] Key type lgssc unregistered [13989.266751] LNet: 392406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13989.280540] LNetError: 392406:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13989.293612] LNet: Removed LNI 192.168.203.4@tcp [13990.122239] Key type .llcrypt unregistered [13990.125475] Key type ._llcrypt unregistered [13991.128264] Key type ._llcrypt registered [13991.130359] Key type .llcrypt registered [13991.480318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13991.488638] alg: No test for adler32 (adler32-zlib) [13992.524345] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [13992.756064] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [13994.544173] Key type lgssc registered [13996.004921] Lustre: Echo OBD driver; http://www.lustre.org/ [14009.430692] Lustre: DEBUG MARKER: Iteration 42 [14009.904622] LustreError: 393240:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14009.908201] LustreError: 393242:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14009.919774] LustreError: 393240:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [14010.994931] Lustre: Mounted lustre-client [14012.712138] LustreError: 393347:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9406d4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14012.853070] Lustre: Unmounted lustre-client [14016.222220] Key type lgssc unregistered [14016.538046] LNet: 393599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14016.550259] LNetError: 393599:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14016.585430] LNet: Removed LNI 192.168.203.4@tcp [14017.374241] Key type .llcrypt unregistered [14017.381217] Key type ._llcrypt unregistered [14018.762724] Key type ._llcrypt registered [14018.765792] Key type .llcrypt registered [14019.082784] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14019.122069] alg: No test for adler32 (adler32-zlib) [14020.473678] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14020.757113] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14022.528799] Key type lgssc registered [14023.953037] Lustre: Echo OBD driver; http://www.lustre.org/ [14038.004310] Lustre: DEBUG MARKER: Iteration 43 [14038.600812] LustreError: 394434:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14038.601463] LustreError: 394433:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14038.617896] LustreError: 394434:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [14039.686329] Lustre: Mounted lustre-client [14041.484872] LustreError: 394549:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943d675800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14041.595668] Lustre: Unmounted lustre-client [14044.624439] Key type lgssc unregistered [14044.978264] LNet: 394795:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14044.983288] LNetError: 394795:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14045.004184] LNet: Removed LNI 192.168.203.4@tcp [14045.793685] Key type .llcrypt unregistered [14045.805916] Key type ._llcrypt unregistered [14046.978753] Key type ._llcrypt registered [14047.003451] Key type .llcrypt registered [14047.469929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14047.485924] alg: No test for adler32 (adler32-zlib) [14048.750147] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14049.048534] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14050.800231] Key type lgssc registered [14052.245733] Lustre: Echo OBD driver; http://www.lustre.org/ [14066.508776] Lustre: DEBUG MARKER: Iteration 44 [14067.028976] LustreError: 395628:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14067.042546] LustreError: 395632:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14067.051298] LustreError: 395628:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [14068.236109] Lustre: Mounted lustre-client [14070.189888] LustreError: 395737:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9433c6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14070.271111] Lustre: Unmounted lustre-client [14073.537841] Key type lgssc unregistered [14073.825604] LNet: 395987:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14073.839721] LNetError: 395987:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14073.861978] LNet: Removed LNI 192.168.203.4@tcp [14074.571283] Key type .llcrypt unregistered [14074.574050] Key type ._llcrypt unregistered [14075.536570] Key type ._llcrypt registered [14075.539967] Key type .llcrypt registered [14075.705770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14075.725360] alg: No test for adler32 (adler32-zlib) [14076.990159] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14077.362311] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14079.088164] Key type lgssc registered [14080.562095] Lustre: Echo OBD driver; http://www.lustre.org/ [14095.586921] Lustre: DEBUG MARKER: Iteration 45 [14096.376686] LustreError: 396822:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14096.379348] LustreError: 396826:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14096.427794] LustreError: 396822:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4959 [14097.617228] Lustre: Mounted lustre-client [14099.821537] LustreError: 396936:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9406ad1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14099.998427] Lustre: Unmounted lustre-client [14103.383997] Key type lgssc unregistered [14103.674285] LNet: 397180:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14103.682853] LNetError: 397180:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14103.711097] LNet: Removed LNI 192.168.203.4@tcp [14104.386257] Key type .llcrypt unregistered [14104.389116] Key type ._llcrypt unregistered [14105.126626] Key type ._llcrypt registered [14105.128640] Key type .llcrypt registered [14105.738447] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14105.755755] alg: No test for adler32 (adler32-zlib) [14107.033645] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14107.446555] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14109.264173] Key type lgssc registered [14110.653526] Lustre: Echo OBD driver; http://www.lustre.org/ [14125.344513] Lustre: DEBUG MARKER: Iteration 46 [14125.877873] LustreError: 398014:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14125.879272] LustreError: 398015:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14125.895000] LustreError: 398014:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [14127.022022] Lustre: Mounted lustre-client [14128.714744] LustreError: 398129:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9409201000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14128.815250] Lustre: Unmounted lustre-client [14131.491822] Key type lgssc unregistered [14131.769285] LNet: 398379:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14131.775736] LNetError: 398379:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14131.809479] LNet: Removed LNI 192.168.203.4@tcp [14132.506155] Key type .llcrypt unregistered [14132.513467] Key type ._llcrypt unregistered [14133.613794] Key type ._llcrypt registered [14133.616173] Key type .llcrypt registered [14133.948627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14133.962298] alg: No test for adler32 (adler32-zlib) [14135.079820] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14135.366401] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14137.096208] Key type lgssc registered [14138.417639] Lustre: Echo OBD driver; http://www.lustre.org/ [14150.090291] Lustre: DEBUG MARKER: Iteration 47 [14150.358666] LustreError: 399212:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14150.364142] LustreError: 399210:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14150.369370] LustreError: 399212:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [14151.362560] Lustre: Mounted lustre-client [14153.127247] LustreError: 399317:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9408121000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14153.208428] Lustre: Unmounted lustre-client [14156.407192] Key type lgssc unregistered [14156.769955] LNet: 399569:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14156.789488] LNetError: 399569:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14156.825392] LNet: Removed LNI 192.168.203.4@tcp [14157.575161] Key type .llcrypt unregistered [14157.577912] Key type ._llcrypt unregistered [14158.577204] Key type ._llcrypt registered [14158.587035] Key type .llcrypt registered [14159.172285] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14159.189659] alg: No test for adler32 (adler32-zlib) [14160.253988] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14160.432809] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14162.064163] Key type lgssc registered [14163.147705] Lustre: Echo OBD driver; http://www.lustre.org/ [14175.211087] Lustre: DEBUG MARKER: Iteration 48 [14175.783271] LustreError: 400403:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14175.786267] LustreError: 400404:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14175.802940] LustreError: 400403:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [14176.902441] Lustre: Mounted lustre-client [14178.597673] LustreError: 400514:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d94056cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14178.735113] Lustre: Unmounted lustre-client [14181.311184] Key type lgssc unregistered [14181.572973] LNet: 400760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14181.580729] LNetError: 400760:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14181.613580] LNet: Removed LNI 192.168.203.4@tcp [14182.239653] Key type .llcrypt unregistered [14182.241781] Key type ._llcrypt unregistered [14182.861098] Key type ._llcrypt registered [14182.866914] Key type .llcrypt registered [14183.340307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14183.363871] alg: No test for adler32 (adler32-zlib) [14184.424277] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14184.622576] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14186.361938] Key type lgssc registered [14187.584863] Lustre: Echo OBD driver; http://www.lustre.org/ [14200.437930] Lustre: DEBUG MARKER: Iteration 49 [14200.824854] LustreError: 401593:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14200.825024] LustreError: 401594:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14200.847598] LustreError: 401593:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [14202.011480] Lustre: Mounted lustre-client [14203.758405] LustreError: 401707:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9410274000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14203.881442] Lustre: Unmounted lustre-client [14206.604903] Key type lgssc unregistered [14206.917487] LNet: 401953:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14206.933189] LNetError: 401953:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14207.976512] LNet: Removed LNI 192.168.203.4@tcp [14208.777304] Key type .llcrypt unregistered [14208.790471] Key type ._llcrypt unregistered [14209.557341] Key type ._llcrypt registered [14209.560131] Key type .llcrypt registered [14210.021807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14210.042738] alg: No test for adler32 (adler32-zlib) [14211.360249] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14211.681973] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14213.408159] Key type lgssc registered [14214.482129] Lustre: Echo OBD driver; http://www.lustre.org/ [14227.232612] Lustre: DEBUG MARKER: Iteration 50 [14227.587620] LustreError: 402784:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [14227.597431] LustreError: 402789:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [14227.607926] LustreError: 402784:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [14228.702404] Lustre: Mounted lustre-client [14230.028208] LustreError: 402901:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9421b19000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14230.114208] Lustre: Unmounted lustre-client [14233.057787] Key type lgssc unregistered [14233.312348] LNet: 403151:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14233.320376] LNetError: 403151:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14233.352670] LNet: Removed LNI 192.168.203.4@tcp [14234.057384] Key type .llcrypt unregistered [14234.061563] Key type ._llcrypt unregistered [14234.936413] Key type ._llcrypt registered [14234.940120] Key type .llcrypt registered [14235.553732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [14235.573329] alg: No test for adler32 (adler32-zlib) [14236.663573] Lustre: Lustre: Build Version: 2.17.51_77_gf2747a6 [14237.014593] LNet: Added LNI 192.168.203.4@tcp [8/256/0/180] [14238.768261] Key type lgssc registered [14240.216185] Lustre: Echo OBD driver; http://www.lustre.org/ [14253.891905] Lustre: Mounted lustre-client [14254.592156] Lustre: Mounted lustre-client [14262.004937] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 19:18:00 (1775776680) [14279.136224] Lustre: 404516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776683/real 1775776683] req@ffff8d94308b0380 x1862036783902080/t0(0) o36->lustre-MDT0000-mdc-ffff8d9421b1a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1775776699 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [14279.180328] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14279.243680] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14295.520117] Lustre: 404516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776699/real 1775776699] req@ffff8d94308b0380 x1862036783902080/t0(0) o36->lustre-MDT0000-mdc-ffff8d9421b1a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1775776715 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [14295.541811] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14295.558512] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14310.880190] Lustre: 404516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776715/real 1775776715] req@ffff8d94308b0380 x1862036783902080/t0(0) o36->lustre-MDT0000-mdc-ffff8d9421b1a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1775776731 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [14310.895052] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14310.912926] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14327.264919] Lustre: 404516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776731/real 1775776731] req@ffff8d94308b0380 x1862036783902080/t0(0) o36->lustre-MDT0000-mdc-ffff8d9421b1a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1775776747 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [14327.318759] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14327.395102] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14343.648632] Lustre: 404516:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776747/real 1775776747] req@ffff8d94308b0380 x1862036783902080/t0(0) o36->lustre-MDT0000-mdc-ffff8d9421b1a000@192.168.203.104@tcp:12/10 lens 496/440 e 0 to 1 dl 1775776763 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [14343.722833] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection to lustre-MDT0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14343.781922] Lustre: lustre-MDT0000-mdc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14346.063209] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 19:19:24 (1775776764) [14358.514196] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 19:19:37 (1775776777) [14373.434531] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 19:19:52 (1775776792) [14381.373703] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 19:20:00 (1775776800) [14412.169760] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 19:20:30 (1775776830) [14445.109877] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 19:21:04 (1775776864) [14452.336544] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 19:21:11 (1775776871) [14452.655813] LustreError: 409303:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [14454.744246] LustreError: 409303:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [14460.730166] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 19:21:19 (1775776879) [14481.888155] Lustre: 403344:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776886/real 1775776886] req@ffff8d942e9b4e00 x1862036784960768/t0(0) o4->lustre-OST0000-osc-ffff8d9421b1a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775776902 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [14481.888193] Lustre: lustre-OST0000-osc-ffff8d9421b1a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14481.908096] Lustre: 403344:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [14498.146220] Lustre: 403345:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776902/real 1775776902] req@ffff8d943f38fb80 x1862036784960000/t0(0) o4->lustre-OST0000-osc-ffff8d9421b1a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775776918 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [14498.161530] Lustre: 403345:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [14498.168841] Lustre: lustre-OST0000-osc-ffff8d9421b1a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14498.224317] Lustre: lustre-OST0000-osc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14514.532645] Lustre: lustre-OST0000-osc-ffff8d9421b1a000: Connection restored to 192.168.203.104@tcp (at 192.168.203.104@tcp) [14546.400306] Lustre: 403344:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775776950/real 1775776950] req@ffff8d942e9b4e00 x1862036784960768/t0(0) o4->lustre-OST0000-osc-ffff8d9421b1a000@192.168.203.104@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775776966 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [14546.415347] Lustre: 403344:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [14546.418630] Lustre: lustre-OST0000-osc-ffff8d9421b1a000: Connection to lustre-OST0000 (at 192.168.203.104@tcp) was lost; in progress operations using this service will wait for recovery to complete [14546.428041] Lustre: Skipped 2 previous similar messages [14554.720976] Lustre: DEBUG MARKER: oleg304-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8d9421b1a000.ost_server_uuid 50 [14555.468763] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8d9421b1a000.ost_server_uuid in FULL state after 0 sec [14556.661306] Lustre: DEBUG MARKER: cleanup: ====================================================== [14557.867997] Lustre: DEBUG MARKER: == sanityn test complete, duration 14266 sec ============= 19:22:57 (1775776977) [14558.957259] Lustre: DEBUG MARKER: === sanityn: start cleanup 19:22:58 (1775776978) === [14925.150438] LustreError: 411395:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d943d677800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14925.226621] Lustre: Unmounted lustre-client [14929.157136] Lustre: DEBUG MARKER: === sanityn: finish cleanup 19:29:07 (1775777347) === [14931.369903] LustreError: 411703:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8d9421b1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [14931.398622] LustreError: 411703:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [14931.492658] Lustre: Unmounted lustre-client [14983.480437] Key type lgssc unregistered [14983.792361] LNet: 412388:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [14983.804897] LNetError: 412388:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [14983.846364] LNet: Removed LNI 192.168.203.4@tcp [14984.553370] Key type .llcrypt unregistered [14984.557761] Key type ._llcrypt unregistered