[ 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 401692538 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.003292] x2apic enabled [ 0.004013] Switched APIC routing to physical x2apic. [ 0.005018] kvm-guest: setup PV IPIs [ 0.008000] ..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.008018] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.010161] LSM: Security Framework initializing [ 0.011061] Yama: becoming mindful. [ 0.012030] SELinux: Initializing. [ 0.013050] *** VALIDATE selinux *** [ 0.020708] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025259] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026126] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027090] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028093] *** VALIDATE tmpfs *** [ 0.029407] *** VALIDATE proc *** [ 0.030198] *** VALIDATE cgroup *** [ 0.031006] *** VALIDATE cgroup2 *** [ 0.033167] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035074] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037022] Spectre V2 : User space: Vulnerable [ 0.038004] Speculative Store Bypass: Vulnerable [ 0.041175] debug: unmapping init [mem 0xffffffffaac59000-0xffffffffaac60fff] [ 0.043250] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044608] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045019] ... version: 2 [ 0.046008] ... bit width: 48 [ 0.047007] ... generic registers: 4 [ 0.048007] ... value mask: 0000ffffffffffff [ 0.049008] ... max period: 00007fffffffffff [ 0.050008] ... fixed-purpose events: 3 [ 0.051007] ... event mask: 000000070000000f [ 0.052249] rcu: Hierarchical SRCU implementation. [ 0.054445] smp: Bringing up secondary CPUs ... [ 0.055520] x86: Booting SMP configuration: [ 0.056014] .... node #0, CPUs: #1 #2 #3 [ 0.065128] smp: Brought up 1 node, 4 CPUs [ 0.067009] smpboot: Max logical packages: 1 [ 0.068022] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.209028] node 0 deferred pages initialised in 139ms [ 0.215233] devtmpfs: initialized [ 0.216330] x86/mm: Memory block size: 128MB [ 0.218720] gcov: version magic: 0x41383552 [ 0.220126] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.221096] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.222469] pinctrl core: initialized pinctrl subsystem [ 0.223296] [ 0.224008] ************************************************************* [ 0.225026] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.226019] ** ** [ 0.227063] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.228007] ** ** [ 0.229007] ** This means that this kernel is built to expose internal ** [ 0.230014] ** IOMMU data structures, which may compromise security on ** [ 0.231015] ** your system. ** [ 0.232016] ** ** [ 0.233018] ** If you see this message and you are not debugging the ** [ 0.234014] ** kernel, report this immediately to your vendor! ** [ 0.235015] ** ** [ 0.236017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.237014] ************************************************************* [ 0.239088] NET: Registered protocol family 16 [ 0.240496] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.241054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.242056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.244095] cpuidle: using governor menu [ 0.246103] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.247727] PCI: Using configuration type 1 for base access [ 0.250144] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.263118] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.265034] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.268350] cryptd: max_cpu_qlen set to 1000 [ 0.269300] ACPI: Added _OSI(Module Device) [ 0.272033] ACPI: Added _OSI(Processor Device) [ 0.273011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.275011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.280552] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.285734] ACPI: Interpreter enabled [ 0.288071] ACPI: PM: (supports S0 S3 S4 S5) [ 0.290014] ACPI: Using IOAPIC for interrupt routing [ 0.294201] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.297706] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.309000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.311055] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.314025] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.317110] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.320499] acpiphp: Slot [2] registered [ 0.322092] acpiphp: Slot [5] registered [ 0.323105] acpiphp: Slot [6] registered [ 0.323985] acpiphp: Slot [3] registered [ 0.325059] acpiphp: Slot [4] registered [ 0.325905] acpiphp: Slot [7] registered [ 0.326091] acpiphp: Slot [8] registered [ 0.327129] acpiphp: Slot [9] registered [ 0.327928] acpiphp: Slot [10] registered [ 0.329052] acpiphp: Slot [11] registered [ 0.329813] acpiphp: Slot [12] registered [ 0.331051] acpiphp: Slot [13] registered [ 0.332031] acpiphp: Slot [14] registered [ 0.333100] acpiphp: Slot [15] registered [ 0.335098] acpiphp: Slot [16] registered [ 0.336049] acpiphp: Slot [17] registered [ 0.337091] acpiphp: Slot [18] registered [ 0.338077] acpiphp: Slot [19] registered [ 0.339107] acpiphp: Slot [20] registered [ 0.340056] acpiphp: Slot [21] registered [ 0.341051] acpiphp: Slot [22] registered [ 0.342068] acpiphp: Slot [23] registered [ 0.343052] acpiphp: Slot [24] registered [ 0.343782] acpiphp: Slot [25] registered [ 0.345055] acpiphp: Slot [26] registered [ 0.345928] acpiphp: Slot [27] registered [ 0.347188] acpiphp: Slot [28] registered [ 0.350090] acpiphp: Slot [29] registered [ 0.351131] acpiphp: Slot [30] registered [ 0.353105] acpiphp: Slot [31] registered [ 0.354076] PCI host bridge to bus 0000:00 [ 0.355015] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.357020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.359030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.362033] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.365033] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.368035] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.370242] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.373022] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.375415] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.384820] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.390089] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.393025] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.396023] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.399026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.401064] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.402655] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.405051] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.407759] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.416000] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.422793] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.426008] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.431025] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.435012] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.439016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.451021] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.458730] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.462011] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.466015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.480021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.492790] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.495451] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.498514] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.500303] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.502236] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.506077] iommu: Default domain type: Passthrough [ 0.507529] SCSI subsystem initialized [ 0.508000] ACPI: bus type USB registered [ 0.508000] usbcore: registered new interface driver usbfs [ 0.509053] usbcore: registered new interface driver hub [ 0.510063] usbcore: registered new device driver usb [ 0.511171] pps_core: LinuxPPS API ver. 1 registered [ 0.512010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.514077] PTP clock support registered [ 0.516125] EDAC MC: Ver: 3.0.0 [ 0.517334] PCI: Using ACPI for IRQ routing [ 0.520160] NetLabel: Initializing [ 0.521008] NetLabel: domain hash size = 128 [ 0.523009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.525097] NetLabel: unlabeled traffic allowed by default [ 0.528364] vgaarb: loaded [ 0.530375] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.532013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.539272] clocksource: Switched to clocksource kvm-clock [ 0.655271] VFS: Disk quotas dquot_6.6.0 [ 0.656382] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.657775] *** VALIDATE ramfs *** [ 0.658597] *** VALIDATE hugetlbfs *** [ 0.659542] pnp: PnP ACPI init [ 0.661734] pnp: PnP ACPI: found 6 devices [ 0.678360] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.681653] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.683939] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.686027] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.688332] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.690795] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.693477] NET: Registered protocol family 2 [ 0.695705] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.701280] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.706331] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.719230] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.722839] TCP: Hash tables configured (established 65536 bind 65536) [ 0.727509] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.731945] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.735494] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.741094] NET: Registered protocol family 1 [ 0.745589] RPC: Registered named UNIX socket transport module. [ 0.748605] RPC: Registered udp transport module. [ 0.752289] RPC: Registered tcp transport module. [ 0.754853] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.758540] NET: Registered protocol family 44 [ 0.760793] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.768083] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.772331] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.775188] PCI: CLS 0 bytes, default 64 [ 0.778198] Unpacking initramfs... [ 2.776985] debug: unmapping init [mem 0xffff9d557cc64000-0xffff9d557ffcffff] [ 2.784303] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.787341] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.792119] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.225368] Initialise system trusted keyrings [ 4.229269] Key type blacklist registered [ 4.230979] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.246708] zbud: loaded [ 4.252723] *** VALIDATE nfs *** [ 4.254144] *** VALIDATE nfs4 *** [ 4.256585] pstore: using deflate compression [ 4.264283] Platform Keyring initialized [ 4.491362] NET: Registered protocol family 38 [ 4.493967] Key type asymmetric registered [ 4.495168] Asymmetric key parser 'x509' registered [ 4.497900] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.500866] io scheduler mq-deadline registered [ 4.502292] io scheduler kyber registered [ 4.503862] io scheduler bfq registered [ 4.505614] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.507951] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.510576] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.513299] ACPI: Power Button [PWRF] [ 4.518912] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.531156] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.550523] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.586366] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.617070] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.621526] Non-volatile memory driver v1.3 [ 4.622765] Linux agpgart interface v0.103 [ 4.655287] virtio_blk virtio1: [vda] 134496 512-byte logical blocks (68.9 MB/65.7 MiB) [ 4.657609] vda: detected capacity change from 0 to 68861952 [ 4.679949] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.682658] vdb: detected capacity change from 0 to 1073741824 [ 4.689883] libphy: Fixed MDIO Bus: probed [ 4.695731] usbcore: registered new interface driver usbserial_generic [ 4.698170] usbserial: USB Serial support registered for generic [ 4.700253] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.704682] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.706375] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.708434] mousedev: PS/2 mouse device common for all mice [ 4.711823] rtc_cmos 00:05: RTC can wake from S4 [ 4.715137] rtc_cmos 00:05: registered as rtc0 [ 4.716705] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.719791] intel_pstate: CPU model not supported [ 4.722771] hid: raw HID events driver (C) Jiri Kosina [ 4.725492] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.727355] usbcore: registered new interface driver usbhid [ 4.734434] usbhid: USB HID core driver [ 4.737092] drop_monitor: Initializing network drop monitor service [ 4.739411] Initializing XFRM netlink socket [ 4.742542] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.742724] NET: Registered protocol family 10 [ 4.751074] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.754958] Segment Routing with IPv6 [ 4.756546] NET: Registered protocol family 17 [ 4.758555] mpls_gso: MPLS GSO support [ 4.765409] RAS: Correctable Errors collector initialized. [ 4.767469] AVX version of gcm_enc/dec engaged. [ 4.769206] AES CTR mode by8 optimization enabled [ 4.890428] sched_clock: Marking stable (4890339445, 0)->(5743859647, -853520202) [ 4.897718] registered taskstats version 1 [ 4.900657] Loading compiled-in X.509 certificates [ 4.907519] zswap: loaded using pool lzo/zbud [ 4.965040] Key type big_key registered [ 4.985613] Key type encrypted registered [ 4.988614] ima: No TPM chip found, activating TPM-bypass! [ 4.991884] ima: Allocated hash algorithm: sha1 [ 4.994448] ima: No architecture policies found [ 4.996280] evm: Initialising EVM extended attributes: [ 4.998685] evm: security.selinux [ 4.999958] evm: security.ima [ 5.002541] evm: security.capability [ 5.004730] evm: HMAC attrs: 0x1 [ 5.008723] rtc_cmos 00:05: setting system clock to 2026-04-29 00:40:12 UTC (1777423212) [ 5.057977] debug: unmapping init [mem 0xffffffffabc03000-0xffffffffabdfffff] [ 5.069394] debug: unmapping init [mem 0xffffffffaa982000-0xffffffffaac58fff] [ 5.080447] Write protecting the kernel read-only data: 28672k [ 5.095510] debug: unmapping init [mem 0xffffffffa9003000-0xffffffffa91fffff] [ 5.100552] debug: unmapping init [mem 0xffffffffa9914000-0xffffffffa99fffff] [ 5.170267] 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) [ 5.198091] systemd[1]: Detected virtualization kvm. [ 5.199839] systemd[1]: Detected architecture x86-64. [ 5.201926] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.231549] systemd[1]: No hostname configured. [ 5.234282] systemd[1]: Set hostname to . [ 5.237443] random: systemd: uninitialized urandom read (16 bytes read) [ 5.240974] systemd[1]: Initializing machine ID from random generator. [ 6.130526] random: systemd: uninitialized urandom read (16 bytes read) [ 6.141885] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 6.160496] random: systemd: uninitialized urandom read (16 bytes read) [ 6.179913] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 6.212866] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Timers. [ 6.270914] urandom_read: 2 callbacks suppressed [ 6.270926] random: systemd: uninitialized urandom read (16 bytes read) [ OK ] Listening on Journal Socket. [ 6.301809] random: systemd: uninitialized urandom read (16 bytes read) Starting Create list of required st…ce nodes for the current kernel... [ 6.690844] random: systemd: uninitialized urandom read (16 bytes read) [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. Starting 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. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 10.017722] device-mapper: uevent: version 1.0.3 [ 10.019950] 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 ] [ 10.999438] random: fast init done Started dracut pre-trigger hook. Starting udev Coldplug all Devices... [ OK ] Started udev Coldplug all Devices. Mounting Kernel Configuration File System... Starting dracut initqueue hook... [ OK ] Mounted Kernel Configuration File System. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ 12.343668] virtio_net virtio0 ens2: renamed from eth0 [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 12.460180] scsi host0: ata_piix [ 12.549814] scsi host1: ata_piix [ 12.552142] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 12.555779] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 19.697885] random: crng init done [ 19.705015] random: 2 urandom warning(s) missed due to ratelimiting [ 20.726026] hrtimer: interrupt took 4033605 ns [ 24.100049] dracut-initqueue[573]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 26.274621] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target 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 dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 30.581283] printk: systemd: 24 output lines suppressed due to ratelimiting [ 31.792675] SELinux: Disabled at runtime. [ 31.941780] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 31.964278] systemd[1]: Detected virtualization kvm. [ 31.968984] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 34.069984] systemd[1]: initrd-switch-root.service: Succeeded. [ 34.088074] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 34.108717] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 34.125244] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 34.142540] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 34.162916] systemd[1]: Starting Journal Service... Starting Journal Service... [ 34.210729] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Control Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... Starting udev Coldplug all Devices... [ OK [ 34.846788] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 36.002605] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 37.268187] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 37.423866] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 38.518411] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 38.612404] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 44.274874] Key type dns_resolver registered [ *** ] A start job is running for Configur…only root support (10s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ **] A start job is running for Configur…only root support (11s / no limit)[ 45.992241] NFS: Registering the id_resolver key type [ 46.012775] Key type id_resolver registered [ 46.027127] Key type id_legacy registered [ *] A start job is running for Configur…only root support (12s / no limit) [ **] A start job is running for Configur…only root support (12s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg252-client login: [ 110.637926] libcfs: loading out-of-tree module taints kernel. [ 110.804177] Key type ._llcrypt registered [ 110.822545] Key type .llcrypt registered [ 111.449960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 111.458013] alg: No test for adler32 (adler32-zlib) [ 112.609236] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 113.107501] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 114.775555] Key type lgssc registered [ 115.820253] Lustre: Echo OBD driver; http://www.lustre.org/ [ 202.397633] Lustre: Mounted lustre-client [ 206.108633] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 218.548414] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing check_logdir /tmp/testlogs/ [ 221.701672] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing yml_node [ 224.798848] Lustre: DEBUG MARKER: Client: 2.17.51.75 [ 226.889963] Lustre: DEBUG MARKER: MDS: 2.17.51.75 [ 228.319239] Lustre: lustre-OST0000-osc-ffff9d55cafb6000: disconnect after 24s idle [ 228.765283] Lustre: DEBUG MARKER: OSS: 2.17.51.75 [ 229.977445] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Apr 28 20:43:56 EDT 2026 [ 242.085064] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 242.966629] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 244.018432] Lustre: DEBUG MARKER: === sanityn: start setup 20:44:10 (1777423450) === [ 244.519769] Lustre: Mounted lustre-client [ 247.015516] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing check_config_client /mnt/lustre [ 260.203163] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 265.186329] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 20s idle [ 265.188945] Lustre: Skipped 1 previous similar message [ 267.742336] Lustre: DEBUG MARKER: === sanityn: finish setup 20:44:34 (1777423474) === [ 269.581851] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 20:44:36 (1777423476) [ 275.408683] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 20:44:41 (1777423481) [ 280.106202] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 20:44:46 (1777423486) [ 284.516676] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 20:44:51 (1777423491) [ 289.063729] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 20:44:55 (1777423495) [ 293.284573] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 20:44:59 (1777423499) [ 298.058645] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 20:45:04 (1777423504) [ 303.271825] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 20:45:09 (1777423509) [ 304.514819] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 305.794976] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 20:45:12 (1777423512) [ 311.458449] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 20:45:17 (1777423517) [ 316.853475] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 20:45:23 (1777423523) [ 322.706542] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 20:45:29 (1777423529) [ 326.626421] Lustre: lustre-OST0001-osc-ffff9d55cafb6000: disconnect after 21s idle [ 326.634442] Lustre: Skipped 1 previous similar message [ 327.837514] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 20:45:34 (1777423534) [ 332.499499] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 20:45:38 (1777423538) [ 337.198256] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 20:45:43 (1777423543) [ 341.996123] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 24s idle [ 342.421352] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 20:45:48 (1777423548) [ 348.070606] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 20:45:54 (1777423554) [ 353.466405] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 20:45:59 (1777423559) [ 359.770622] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 20:46:05 (1777423565) [ 364.982092] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 20:46:11 (1777423571) [ 370.624818] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 20:46:16 (1777423576) [ 371.129421] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 516.177345] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 20:48:42 (1777423722) [ 521.923997] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 20:48:48 (1777423728) [ 527.037086] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 20:48:53 (1777423733) [ 532.192793] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 20:48:58 (1777423738) [ 537.794663] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 20:49:04 (1777423744) [ 543.144733] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 20:49:09 (1777423749) [ 544.764084] Lustre: DEBUG MARKER: chmod [ 550.036039] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 20:49:16 (1777423756) [ 578.891786] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7531520KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 590.761456] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 20:49:57 (1777423797) [ 627.278316] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 20:50:33 (1777423833) [ 647.937464] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:50:54 (1777423854) [ 649.761748] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 651.117827] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:50:57 (1777423857) [ 681.142772] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:51:27 (1777423887) [ 685.023206] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 20s idle [ 685.783388] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:51:32 (1777423892) [ 686.585522] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.657733] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.730981] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.779790] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.836320] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.892509] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.945895] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 686.989511] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.057587] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.119898] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.203401] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.280710] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.334284] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.384199] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.451624] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.522044] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.572848] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.635708] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.682770] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.721356] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.767179] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.821085] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.879654] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 687.935927] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.004964] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.048990] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.096569] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.205043] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.272083] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.330230] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.394626] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.463699] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.531496] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.587502] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.636235] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.681978] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.739977] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.800357] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.850064] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.908971] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 688.982376] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.050471] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.103323] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.168290] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.236629] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.305573] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.354429] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.409490] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.447652] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.503623] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.564691] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.612546] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.661765] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.711760] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.756716] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.794745] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.840080] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.893840] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 689.950565] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.007666] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.053611] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.099153] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.152472] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.205392] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.244189] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.288454] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.337379] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.385170] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.456362] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.509019] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.577597] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.640439] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.693834] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.733392] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.781331] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.825529] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.868489] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.915462] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.951793] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 690.993835] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.038106] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.078499] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.134979] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.210890] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.261944] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.312750] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.342516] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.388702] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.423200] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.460368] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.495909] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.537097] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.588546] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.626272] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.669605] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.711699] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.746568] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.776241] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.819936] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.852345] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.889913] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 691.935413] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.003337] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.077272] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.138068] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.207096] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.253522] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.290893] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.331249] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.378531] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.437548] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.477605] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.516221] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.586946] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.646350] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.721439] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.774911] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.828758] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.877692] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.921473] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 692.960346] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.015560] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.062311] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.106280] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.146831] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.185863] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.229980] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.269373] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.313347] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.389268] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.444488] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.488899] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.548357] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.609503] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.684735] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.745297] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.785631] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.839310] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.896866] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 693.965177] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.066883] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.132438] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.192081] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.241618] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.294564] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.365353] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.405044] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.454053] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.498528] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.568940] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.620871] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.673590] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.735315] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.785941] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.837497] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.884407] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.927439] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 694.971545] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.012596] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.055212] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.105700] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.148449] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.183823] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.232199] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.279425] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.327103] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.371238] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.421892] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.457209] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.494605] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.575588] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.638136] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.698948] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.746326] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.780805] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.823936] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.861351] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.900599] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.943156] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 695.978268] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.020402] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.055283] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.088390] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.136740] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.184945] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.236501] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.289159] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.348976] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.410959] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.456762] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.501474] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.551727] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.584071] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.614619] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.650310] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.694265] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.737967] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.780515] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.824424] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.870563] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.917757] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 696.958806] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.002752] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.053113] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.091596] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.135123] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.172828] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.218205] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.262275] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.308690] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.355180] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.403621] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.449960] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.508576] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.547259] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.607979] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.663842] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.719175] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.763737] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.828587] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.876968] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.916745] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 697.987202] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.040470] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.100261] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.135330] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.196278] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.239180] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.279116] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.326586] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.421493] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.470448] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.497868] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.534446] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.568645] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.608893] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.638196] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.676936] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.706695] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.772961] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.819163] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.859812] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.903535] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.953894] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 698.996851] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.055262] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.099592] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.143695] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.200061] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.258358] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.292346] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.341830] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.402093] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.465380] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.540868] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.590620] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.637120] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.678305] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.734659] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.785480] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.845605] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.922591] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 699.981294] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.082461] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.168613] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.239802] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.289166] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.332172] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.395364] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.447116] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.492067] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.531697] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.578857] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.625249] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.693142] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.747925] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.800918] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.849411] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.898832] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 700.970816] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.023426] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.093542] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.151964] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.194528] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.235518] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.274572] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.322990] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.371664] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.414903] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.458349] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.496799] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.536208] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.567890] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.602967] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.635780] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.683782] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.730823] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.796588] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.871580] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 701.924667] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.001026] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.045890] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.089764] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.135234] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.178465] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.222513] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.291499] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.349371] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.395593] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.457677] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.510564] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.552548] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.605269] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.656730] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.695396] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.732186] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.767906] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.808367] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.857600] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.897487] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.943846] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 702.984710] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.015143] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.049520] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.089732] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.131785] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.168201] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.206852] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.245626] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.283650] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.315340] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.357321] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.393323] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.438528] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.487340] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.551831] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.594482] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.641036] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.694247] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.750080] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.814630] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.870716] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.935860] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 703.993861] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.042474] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.099605] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.151414] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.200580] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.237324] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.266742] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.302107] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.338323] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.378896] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.430533] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.474994] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.519936] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.554123] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.587852] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.619850] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.677449] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.728084] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.785956] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.840917] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.899611] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 704.954410] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.032795] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.093505] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.147196] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.196749] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.237582] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.277867] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.325334] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.401939] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.472557] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.507466] Lustre: lustre-OST0001-osc-ffff9d55cafb6000: disconnect after 20s idle [ 705.513628] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.574717] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.615724] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.670945] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.703341] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.750403] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.794839] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.829857] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.865570] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.896760] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 705.927488] rw_seq_cst_vs_d (29437): drop_caches: 3 [ 710.342627] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:51:56 (1777423916) [ 710.630515] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.658323] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.690811] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.735714] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.763469] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.868760] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.903208] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.928778] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.947367] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.974363] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 710.993307] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.018487] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.090951] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.158969] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.308932] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.373081] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.412697] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.509570] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.544956] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.569446] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.622685] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.883236] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.931716] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 711.963877] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.071423] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.095428] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.330249] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.377300] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.409460] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.452587] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.496891] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.565661] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.607378] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.635232] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.667824] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.686134] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.706972] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.810388] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.854655] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 712.877195] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.017837] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.211718] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.235496] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.487915] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.569855] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.641459] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.729834] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.762208] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.853829] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.905246] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 713.949176] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.110453] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.261379] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.296076] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.389269] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.452886] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.570421] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.669690] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 714.947231] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.090608] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.145928] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.340756] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.367496] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.457178] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.558815] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.621840] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.646220] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.676165] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.702199] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.726537] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.802684] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.849483] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.912239] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 715.992475] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 716.421256] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 716.448256] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 716.719227] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 716.897774] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 716.921759] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.140019] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.174415] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.202826] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.242061] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.280571] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.346879] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.380123] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.406280] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.485756] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.505508] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.525643] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.648221] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.834351] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.859634] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 717.918381] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.262976] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.338425] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.370478] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.476202] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.555267] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.641720] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.667220] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.685460] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.717642] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.769652] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.789555] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.916778] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.940125] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 718.963257] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.128196] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.189260] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.260416] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.290691] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.311876] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.335400] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.452398] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.571248] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.603883] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.624923] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 719.694169] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.032525] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.133660] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.295067] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.440595] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.464873] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.488104] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.532707] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.559473] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.578791] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.643622] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.681297] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.740479] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.760761] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.790081] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.956512] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 720.993913] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.036254] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.111095] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.186675] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.241464] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.278622] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.380158] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.470240] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.567332] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.599172] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.669750] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 721.689611] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.043902] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.128444] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.231894] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.255633] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.298561] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.337488] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.668841] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.700814] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.783316] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.820179] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 722.870236] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.366398] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.688133] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.714995] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.740779] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.761347] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.796541] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.939955] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 723.974469] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.061733] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.187743] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.213274] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.365408] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.413905] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.502394] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.561418] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.693892] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.771718] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.846854] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.884599] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.930450] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 724.972525] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.039580] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.092120] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.170117] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.354362] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.383195] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.421484] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.485218] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.555535] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.602428] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.628824] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.668877] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.714179] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.906861] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 725.936208] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.175436] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.253612] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.386106] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.654318] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.678283] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.777054] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.802824] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.823519] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 726.930948] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.219229] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.252626] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.276606] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.371768] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.586379] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.607735] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.624655] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.717368] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.851383] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.904291] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.924811] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 727.963363] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.070388] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.100744] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.168996] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.313948] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.332139] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.378627] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.576930] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.610221] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.645755] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.725557] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.756343] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.896334] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 728.933638] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.150655] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.182506] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.328843] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.359134] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.429222] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.489096] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.529154] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.567227] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.718780] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.747552] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.816782] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.882678] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 729.912605] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 730.015299] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 730.288467] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 730.381181] rw_seq_cst_vs_d (30006): drop_caches: 3 [ 731.103161] Lustre: lustre-OST0000-osc-ffff9d55cafb6000: disconnect after 20s idle [ 731.105744] Lustre: Skipped 1 previous similar message [ 734.449486] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:52:21 (1777423941) [ 738.260672] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:52:24 (1777423944) [ 742.367774] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:52:28 (1777423948) [ 767.518520] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:52:54 (1777423974) [ 767.790417] bash (32459): drop_caches: 3 [ 770.938310] bash (32459): drop_caches: 3 [ 774.274961] bash (32459): drop_caches: 3 [ 777.439355] bash (32459): drop_caches: 3 [ 780.535094] bash (32459): drop_caches: 3 [ 783.622713] bash (32459): drop_caches: 3 [ 786.705575] bash (32459): drop_caches: 3 [ 789.816931] bash (32459): drop_caches: 3 [ 792.905548] bash (32459): drop_caches: 3 [ 796.958083] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:53:23 (1777424003) [ 804.091587] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:53:30 (1777424010) [ 823.382818] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:53:49 (1777424029) [ 824.994817] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 826.154579] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:53:52 (1777424032) [ 830.013350] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:53:56 (1777424036) [ 834.314362] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:54:00 (1777424040) [ 838.623233] Lustre: lustre-OST0001-osc-ffff9d55e05e5000: disconnect after 22s idle [ 838.626704] Lustre: Skipped 1 previous similar message [ 900.570870] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:55:07 (1777424107) [ 904.746189] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:55:11 (1777424111) [ 908.880754] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:55:15 (1777424115) [ 912.885947] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:55:19 (1777424119) [ 913.817670] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 914.829064] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:55:21 (1777424121) [ 919.713144] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:55:26 (1777424126) [ 925.949233] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:55:32 (1777424132) [ 930.567583] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 931.661803] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:55:38 (1777424138) [ 937.375676] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:55:44 (1777424144) [ 937.623248] Lustre: *** cfs_fail_loc=314, val=0*** [ 938.655247] Lustre: *** cfs_fail_loc=314, val=0*** [ 938.657478] Lustre: Skipped 2 previous similar messages [ 942.317708] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:55:49 (1777424149) [ 953.474599] Lustre: *** cfs_fail_loc=314, val=0*** [ 956.400228] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 956.420386] LustreError: lustre-OST0000-osc-ffff9d55e05e5000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 956.436804] Lustre: 2348:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.152@tcp:/lustre/fid: [0x200000402:0x26:0x0]// may get corrupted (rc -108) [ 956.447327] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 957.495942] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:56:04 (1777424164) [ 957.632646] LustreError: 42266:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 960.663124] LustreError: 42266:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 964.073341] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:56:10 (1777424170) [ 968.509796] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:56:15 (1777424175) [ 973.088646] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 974.062778] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:56:20 (1777424180) [ 975.216978] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 976.102599] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:56:22 (1777424182) [ 976.967971] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 978.054973] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:56:24 (1777424184) [ 978.859926] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 979.894679] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:56:26 (1777424186) [ 980.764407] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 981.686550] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:56:28 (1777424188) [ 992.224907] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 22s idle [ 992.230881] Lustre: Skipped 3 previous similar messages [ 1037.255773] Lustre: lustre-OST0001-osc-ffff9d55e05e5000: Connection to lustre-OST0001 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1037.267327] LustreError: lustre-OST0001-osc-ffff9d55e05e5000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1037.274124] LustreError: lustre-OST0001-osc-ffff9d55cafb6000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1037.277058] Lustre: lustre-OST0001-osc-ffff9d55e05e5000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 1037.281842] Lustre: Skipped 1 previous similar message [ 1052.612433] Lustre: lustre-OST0000-osc-ffff9d55cafb6000: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1052.617623] Lustre: Skipped 1 previous similar message [ 1052.623959] LustreError: lustre-OST0000-osc-ffff9d55cafb6000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1052.630466] Lustre: lustre-OST0000-osc-ffff9d55cafb6000: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 1064.694861] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1065.396628] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid in FULL state after 0 sec [ 1067.310531] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1068.057136] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid in IDLE state after 0 sec [ 1070.659718] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1071.344401] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid in FULL state after 0 sec [ 1073.232623] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1074.020910] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid in IDLE state after 0 sec [ 1079.339354] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1080.310443] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d55cafb6000.ost_server_uuid in IDLE state after 0 sec [ 1082.768512] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid 50 [ 1083.525978] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d55cafb6000.ost_server_uuid in IDLE state after 0 sec [ 1084.404569] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:58:11 (1777424291) [ 1085.874838] Lustre: DEBUG MARKER: Race attempt 0 [ 1087.839858] Lustre: DEBUG MARKER: Wait for 48890 48991 for 60 sec... [ 1150.975626] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:59:17 (1777424357) [ 1156.884904] Lustre: DEBUG MARKER: start test - cycle (0) [ 1180.942925] Lustre: DEBUG MARKER: start test - cycle (1) [ 1181.663182] Lustre: lustre-OST0001-osc-ffff9d55e05e5000: disconnect after 20s idle [ 1181.666197] Lustre: Skipped 3 previous similar messages [ 1201.176397] Lustre: DEBUG MARKER: start test - cycle (2) [ 1221.081202] Lustre: DEBUG MARKER: start test - cycle (3) [ 1242.649890] Lustre: DEBUG MARKER: start test - cycle (4) [ 1267.576368] Lustre: DEBUG MARKER: start test - cycle (5) [ 1288.735191] Lustre: DEBUG MARKER: start test - cycle (6) [ 1308.963412] Lustre: DEBUG MARKER: start test - cycle (7) [ 1328.421427] Lustre: DEBUG MARKER: start test - cycle (8) [ 1349.490795] Lustre: DEBUG MARKER: start test - cycle (9) [ 1370.876375] Lustre: DEBUG MARKER: start test - cycle (10) [ 1392.847092] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 21:03:19 (1777424599) [ 1428.805056] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 21:03:55 (1777424635) [ 1431.884072] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 21:03:58 (1777424638) [ 1436.186529] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 21:04:03 (1777424643) [ 1440.321762] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 21:04:07 (1777424647) [ 1440.492506] Lustre: *** cfs_fail_loc=411, val=0*** [ 1443.341756] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1444.152621] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 21:04:10 (1777424650) [ 1454.303865] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 21:04:21 (1777424661) [ 1464.076595] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 21:04:30 (1777424670) [ 1473.430453] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 21:04:40 (1777424680) [ 1473.504756] Lustre: lustre-OST0001-osc-ffff9d55cafb6000: disconnect after 22s idle [ 1473.511245] Lustre: Skipped 4 previous similar messages [ 1483.149887] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 21:04:49 (1777424689) [ 1490.008509] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 21:04:56 (1777424696) [ 1496.562059] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 21:05:03 (1777424703) [ 1503.163905] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 21:05:10 (1777424710) [ 1509.853613] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 21:05:16 (1777424716) [ 1516.618810] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 21:05:23 (1777424723) [ 1523.671973] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 21:05:30 (1777424730) [ 1530.519845] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 21:05:37 (1777424737) [ 1536.430687] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 21:05:43 (1777424743) [ 2154.464209] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 20s idle [ 2154.466383] Lustre: Skipped 4 previous similar messages [ 2273.197336] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 21:18:00 (1777425480) [ 2278.299978] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 21:18:05 (1777425485) [ 2283.538565] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 21:18:10 (1777425490) [ 2288.417249] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 21:18:15 (1777425495) [ 2293.654092] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 21:18:20 (1777425500) [ 2298.726931] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 21:18:25 (1777425505) [ 2303.794193] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 21:18:30 (1777425510) [ 2308.815258] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 21:18:35 (1777425515) [ 2313.702077] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 21:18:40 (1777425520) [ 2331.531437] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 21:18:58 (1777425538) [ 2336.716449] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 21:19:03 (1777425543) [ 2341.860399] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 21:19:08 (1777425548) [ 2347.179486] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 21:19:14 (1777425554) [ 2352.412901] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 21:19:19 (1777425559) [ 2357.741412] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 21:19:24 (1777425564) [ 2363.038277] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 21:19:30 (1777425570) [ 2368.105508] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 21:19:35 (1777425575) [ 2368.595580] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2369.121566] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 21:19:36 (1777425576) [ 2407.554285] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 21:20:14 (1777425614) [ 2828.732700] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:27:15 (1777426035) [ 2833.716878] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:27:20 (1777426040) [ 2838.738909] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:27:25 (1777426045) [ 2844.097266] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:27:31 (1777426051) [ 2849.332376] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:27:36 (1777426056) [ 2854.620221] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:27:41 (1777426061) [ 2859.426869] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:27:46 (1777426066) [ 2864.228410] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:27:51 (1777426071) [ 2869.002976] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:27:56 (1777426076) [ 2869.417914] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2869.888689] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:27:56 (1777426076) [ 2886.623164] Lustre: lustre-OST0000-osc-ffff9d55cafb6000: disconnect after 23s idle [ 2886.625250] Lustre: Skipped 6 previous similar messages [ 2898.420825] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:28:25 (1777426105) [ 2903.390608] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:28:30 (1777426110) [ 2908.520268] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:28:35 (1777426115) [ 2913.659910] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:28:40 (1777426120) [ 2918.720696] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:28:45 (1777426125) [ 2923.986301] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:28:51 (1777426131) [ 2929.089739] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:28:56 (1777426136) [ 2933.718023] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:29:00 (1777426140) [ 2934.212546] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2934.766112] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:29:01 (1777426141) [ 3357.973312] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 21:36:04 (1777426564) [ 3363.249411] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 21:36:10 (1777426570) [ 3368.367531] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 21:36:15 (1777426575) [ 3373.557551] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 21:36:20 (1777426580) [ 3379.010243] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 21:36:26 (1777426586) [ 3384.182607] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 21:36:31 (1777426591) [ 3389.143098] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 21:36:36 (1777426596) [ 3394.458723] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 21:36:41 (1777426601) [ 3399.825343] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 21:36:46 (1777426606) [ 3400.311945] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3400.827795] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 21:36:47 (1777426607) [ 3401.317324] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3401.824622] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 21:36:48 (1777426608) [ 3402.314189] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3402.902536] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 21:36:49 (1777426609) [ 3403.395858] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3403.923024] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 21:36:50 (1777426610) [ 3404.403600] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3404.907855] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 21:36:51 (1777426611) [ 3405.374249] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3405.893832] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 21:36:52 (1777426612) [ 3406.370963] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3406.883162] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 21:36:53 (1777426613) [ 3407.347944] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3407.878091] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 21:36:54 (1777426614) [ 3407.949883] LustreError: 5519:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3410.031060] LustreError: 5519:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3414.933429] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 21:37:02 (1777426622) [ 3419.284212] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 21:37:06 (1777426626) [ 3419.362243] LustreError: 217115:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3423.423115] LustreError: 217115:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3423.429386] LustreError: 217115:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3427.487121] LustreError: 217115:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3427.499908] LustreError: 217121:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3431.559165] LustreError: 217121:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 3433.830021] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 21:37:20 (1777426640) [ 3440.611558] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 21:37:27 (1777426647) [ 3443.668657] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 21:37:30 (1777426650) [ 3447.686943] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 21:37:34 (1777426654) [ 3471.601779] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 21:37:58 (1777426678) [ 3479.142256] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 21:38:06 (1777426686) [ 3486.696907] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 21:38:13 (1777426693) [ 3499.521409] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 21:38:26 (1777426706) [ 3509.328112] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 21:38:36 (1777426716) [ 3509.838594] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3510.384809] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 21:38:37 (1777426717) [ 3523.277326] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 21:38:50 (1777426730) [ 3537.306668] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 21:39:04 (1777426744) [ 3564.630076] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 21:39:31 (1777426771) [ 3605.617601] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 21:40:12 (1777426812) [ 3608.475666] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3610.858785] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 21:40:17 (1777426817) [ 3613.877696] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 21:40:20 (1777426820) [ 3614.471532] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3615.087669] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 21:40:22 (1777426822) [ 3615.629779] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3616.212960] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 21:40:23 (1777426823) [ 3616.711614] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3617.265247] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 21:40:24 (1777426824) [ 3617.768796] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3618.347082] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 21:40:25 (1777426825) [ 3620.677989] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 21:40:27 (1777426827) [ 3622.852720] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 21:40:29 (1777426829) [ 3625.915926] LustreError: lustre-MDT0000-mdc-ffff9d55cafb6000: operation ldlm_enqueue to node 192.168.202.152@tcp failed: rc = -35 [ 3625.919201] LustreError: Skipped 1 previous similar message [ 3628.882359] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 21:40:35 (1777426835) [ 3629.020343] LustreError: 2350:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3631.103112] LustreError: 2350:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3636.003069] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 21:40:43 (1777426843) [ 3649.503244] Lustre: lustre-OST0000-osc-ffff9d55e05e5000: disconnect after 20s idle [ 3649.505149] Lustre: Skipped 4 previous similar messages [ 3656.510637] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 21:41:03 (1777426863) [ 3659.361730] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 21:41:06 (1777426866) [ 3663.558531] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 21:41:10 (1777426870) [ 3668.634986] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 21:41:15 (1777426875) [ 3673.343159] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 21:41:20 (1777426880) [ 3680.947585] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 21:41:27 (1777426887) [ 3688.440776] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 21:41:35 (1777426895) [ 3691.762974] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 21:41:38 (1777426898) [ 3695.634273] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 21:41:42 (1777426902) [ 3703.534466] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 21:41:50 (1777426910) [ 3749.120382] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 21:42:36 (1777426956) [ 3884.264272] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:44:51 (1777427091) [ 4019.975862] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:47:07 (1777427227) [ 4274.143323] Lustre: lustre-OST0001-osc-ffff9d55cafb6000: disconnect after 23s idle [ 4274.146317] Lustre: Skipped 17 previous similar messages [ 4366.084407] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:52:53 (1777427573) [ 4403.431990] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:53:30 (1777427610) [ 4441.132833] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:54:08 (1777427648) [ 4472.416716] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:54:39 (1777427679) [ 4549.635591] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:55:56 (1777427756) [ 4612.806642] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:56:59 (1777427819) [ 4728.276388] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:58:55 (1777427935) [ 4729.250677] LustreError: 258166:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55cafb6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4729.265855] Lustre: Unmounted lustre-client [ 4730.162447] LustreError: 258179:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55e05e5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4730.167475] LustreError: 258179:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 4730.178955] Lustre: Unmounted lustre-client [ 4762.098879] Lustre: Mounted lustre-client [ 4763.634122] Lustre: Mounted lustre-client [ 4764.743743] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4852.376630] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 22:00:59 (1777428059) [ 4859.780888] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:01:06 (1777428066) [ 4862.865421] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:01:09 (1777428069) [ 4913.161702] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:02:00 (1777428120) [ 4942.815262] Lustre: lustre-OST0001-osc-ffff9d55c563c000: disconnect after 24s idle [ 4942.818087] Lustre: Skipped 15 previous similar messages [ 4979.422801] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:03:06 (1777428186) [ 4982.947177] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:03:10 (1777428190) [ 5019.669039] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:03:46 (1777428226) [ 5031.006772] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 22:03:58 (1777428238) [ 5070.621199] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:04:37 (1777428277) [ 5073.343299] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:04:40 (1777428280) [ 5086.112224] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:04:53 (1777428293) [ 5086.620966] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5087.103272] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:04:54 (1777428294) [ 5087.556759] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5088.072896] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:04:55 (1777428295) [ 5088.577930] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5089.112831] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:04:56 (1777428296) [ 5089.690994] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5090.307686] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:04:57 (1777428297) [ 5090.807208] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5091.347206] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 22:04:58 (1777428298) [ 5116.170768] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:05:23 (1777428323) [ 5118.150365] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:05:25 (1777428325) [ 5118.613182] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5119.120824] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:05:26 (1777428326) [ 5126.455907] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 22:05:33 (1777428333) [ 5129.078993] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:05:36 (1777428336) [ 5129.538217] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5130.048598] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:05:37 (1777428337) [ 5130.530223] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5131.040253] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:05:38 (1777428338) [ 5131.533251] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5132.060548] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:05:39 (1777428339) [ 5140.671109] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:05:47 (1777428347) [ 5140.742904] Lustre: DEBUG MARKER: write [ 5140.758023] LustreError: 260099:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5142.763095] Lustre: DEBUG MARKER: kill 287793 [ 5142.765563] LustreError: 287793:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5145.855097] LustreError: 260099:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5148.799073] LustreError: 287793:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5150.839403] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 22:05:57 (1777428357) [ 5152.983208] LustreError: 288398:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5163.071087] LustreError: 288398:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5165.168694] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:06:12 (1777428372) [ 5165.251203] LustreError: 288977:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5167.335048] LustreError: 288977:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 5173.207434] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:06:20 (1777428380) [ 5173.689048] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5174.202956] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:06:21 (1777428381) [ 5176.214058] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:06:23 (1777428383) [ 5178.264741] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:06:25 (1777428385) [ 5180.373187] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:06:27 (1777428387) [ 5182.405490] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:06:29 (1777428389) [ 5184.486814] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:06:31 (1777428391) [ 5186.553048] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:06:33 (1777428393) [ 5189.569670] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5190.150724] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:06:37 (1777428397) [ 5190.741760] Lustre: *** cfs_fail_loc=415, val=0*** [ 5197.221983] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:06:44 (1777428404) [ 5197.664215] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5198.175355] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:06:45 (1777428405) [ 5198.238421] LustreError: 259576:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5198.240692] LustreError: 259576:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 5203.335099] LustreError: 259576:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5203.337368] LustreError: 259576:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 5213.527090] LustreError: 259575:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5213.527090] LustreError: 260099:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5213.527108] LustreError: 260099:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 5213.528986] LustreError: 259575:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 5220.722921] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 22:07:07 (1777428427) [ 5221.204244] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5221.761745] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 22:07:08 (1777428428) [ 5223.986422] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 22:07:11 (1777428431) [ 5226.051163] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 22:07:13 (1777428433) [ 5230.120412] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:07:17 (1777428437) [ 5238.217613] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:07:25 (1777428445) [ 5238.340306] LustreError: 269116:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5238.342280] LustreError: 269116:0:(osc_request.c:2989:osc_build_rpc()) Skipped 5 previous similar messages [ 5244.415313] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:07:31 (1777428451) [ 5245.074968] LustreError: 298917:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c563c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5245.077423] LustreError: 298917:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5245.089442] Lustre: Unmounted lustre-client [ 5245.858661] Lustre: DEBUG MARKER: Iteration 1 [ 5245.967618] LustreError: 299097:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5245.968115] LustreError: 299098:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5245.973154] LustreError: 299097:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5246.015128] Lustre: Mounted lustre-client [ 5247.397925] Key type lgssc unregistered [ 5247.504585] LNet: 299447:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5247.508492] LNetError: 299447:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5247.517737] LNet: Removed LNI 192.168.202.52@tcp [ 5247.798123] Key type .llcrypt unregistered [ 5247.799078] Key type ._llcrypt unregistered [ 5248.062215] Key type ._llcrypt registered [ 5248.063167] Key type .llcrypt registered [ 5248.303927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5248.308575] alg: No test for adler32 (adler32-zlib) [ 5249.269861] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5249.528488] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5251.135219] Key type lgssc registered [ 5251.698041] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5255.524343] Lustre: DEBUG MARKER: Iteration 2 [ 5255.633243] LustreError: 300267:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5255.633614] LustreError: 300268:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5255.637298] LustreError: 300267:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5256.680504] Lustre: Mounted lustre-client [ 5257.123035] LustreError: 300369:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5257.144598] Lustre: Unmounted lustre-client [ 5258.057168] Key type lgssc unregistered [ 5258.164433] LNet: 300613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5258.166870] LNetError: 300613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5258.176674] LNet: Removed LNI 192.168.202.52@tcp [ 5258.391086] Key type .llcrypt unregistered [ 5258.392081] Key type ._llcrypt unregistered [ 5258.638330] Key type ._llcrypt registered [ 5258.639473] Key type .llcrypt registered [ 5258.811822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5258.817036] alg: No test for adler32 (adler32-zlib) [ 5259.674878] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5259.753577] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5261.327136] Key type lgssc registered [ 5261.695382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5265.062639] Lustre: DEBUG MARKER: Iteration 3 [ 5265.195416] LustreError: 301434:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5265.195463] LustreError: 301435:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5265.200969] LustreError: 301434:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5266.266106] Lustre: Mounted lustre-client [ 5266.268658] Lustre: Skipped 1 previous similar message [ 5266.861876] LustreError: 301537:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e19000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5266.865802] LustreError: 301537:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5266.889584] Lustre: Unmounted lustre-client [ 5267.866778] Key type lgssc unregistered [ 5267.983430] LNet: 301779:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5267.985551] LNetError: 301779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5267.993658] LNet: Removed LNI 192.168.202.52@tcp [ 5268.229114] Key type .llcrypt unregistered [ 5268.230132] Key type ._llcrypt unregistered [ 5268.517293] Key type ._llcrypt registered [ 5268.518241] Key type .llcrypt registered [ 5268.686883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5268.691222] alg: No test for adler32 (adler32-zlib) [ 5269.547111] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5269.622395] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5271.199115] Key type lgssc registered [ 5271.599390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5275.015494] Lustre: DEBUG MARKER: Iteration 4 [ 5275.127719] LustreError: 302600:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5275.127785] LustreError: 302601:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5275.132306] LustreError: 302600:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5276.177744] Lustre: Mounted lustre-client [ 5276.179681] Lustre: Skipped 1 previous similar message [ 5276.794701] LustreError: 302707:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5276.798735] LustreError: 302707:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5276.819165] Lustre: Unmounted lustre-client [ 5277.761186] Key type lgssc unregistered [ 5277.886637] LNet: 302950:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5277.889285] LNetError: 302950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5277.898162] LNet: Removed LNI 192.168.202.52@tcp [ 5278.114085] Key type .llcrypt unregistered [ 5278.114974] Key type ._llcrypt unregistered [ 5278.365182] Key type ._llcrypt registered [ 5278.366301] Key type .llcrypt registered [ 5278.550135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5278.557640] alg: No test for adler32 (adler32-zlib) [ 5279.403019] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5279.489232] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5281.063094] Key type lgssc registered [ 5281.402146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5284.476781] Lustre: DEBUG MARKER: Iteration 5 [ 5284.574367] LustreError: 303771:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5284.577097] LustreError: 303772:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5284.578882] LustreError: 303771:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5285.621559] Lustre: Mounted lustre-client [ 5286.049986] LustreError: 303877:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e19800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5286.077067] Lustre: Unmounted lustre-client [ 5287.005633] Key type lgssc unregistered [ 5287.113507] LNet: 304120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5287.117733] LNetError: 304120:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5287.125541] LNet: Removed LNI 192.168.202.52@tcp [ 5287.412139] Key type .llcrypt unregistered [ 5287.413788] Key type ._llcrypt unregistered [ 5287.677051] Key type ._llcrypt registered [ 5287.677912] Key type .llcrypt registered [ 5287.861044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5287.865897] alg: No test for adler32 (adler32-zlib) [ 5288.714525] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5288.793396] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5290.375124] Key type lgssc registered [ 5290.745038] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5294.000985] Lustre: DEBUG MARKER: Iteration 6 [ 5294.093928] LustreError: 304941:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5294.093955] LustreError: 304942:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5294.099103] LustreError: 304941:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5295.144856] Lustre: Mounted lustre-client [ 5295.588792] LustreError: 305045:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85ae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5295.593711] LustreError: 305045:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5295.605295] Lustre: Unmounted lustre-client [ 5296.509264] Key type lgssc unregistered [ 5296.627314] LNet: 305288:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5296.630277] LNetError: 305288:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5296.639302] LNet: Removed LNI 192.168.202.52@tcp [ 5296.897111] Key type .llcrypt unregistered [ 5296.898076] Key type ._llcrypt unregistered [ 5297.159630] Key type ._llcrypt registered [ 5297.161098] Key type .llcrypt registered [ 5297.412989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5297.419356] alg: No test for adler32 (adler32-zlib) [ 5298.287521] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5298.382785] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5299.967114] Key type lgssc registered [ 5300.386241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5303.896958] Lustre: DEBUG MARKER: Iteration 7 [ 5304.002268] LustreError: 306107:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5304.002284] LustreError: 306109:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5304.008628] LustreError: 306107:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5305.070284] Lustre: Mounted lustre-client [ 5305.071292] Lustre: Skipped 1 previous similar message [ 5305.606474] LustreError: 306215:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c9999800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5305.611181] LustreError: 306215:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5305.626096] Lustre: Unmounted lustre-client [ 5306.622961] Key type lgssc unregistered [ 5306.742386] LNet: 306456:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5306.744724] LNetError: 306456:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5306.754352] LNet: Removed LNI 192.168.202.52@tcp [ 5306.975128] Key type .llcrypt unregistered [ 5306.976945] Key type ._llcrypt unregistered [ 5307.254144] Key type ._llcrypt registered [ 5307.255140] Key type .llcrypt registered [ 5307.523311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5307.529675] alg: No test for adler32 (adler32-zlib) [ 5308.406666] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5308.504047] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5310.087152] Key type lgssc registered [ 5310.442073] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5313.909374] Lustre: DEBUG MARKER: Iteration 8 [ 5314.016849] LustreError: 307277:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5314.016893] LustreError: 307276:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5314.021712] LustreError: 307277:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5315.077430] Lustre: Mounted lustre-client [ 5315.528225] LustreError: 307385:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85ad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5315.557887] Lustre: Unmounted lustre-client [ 5316.477493] Key type lgssc unregistered [ 5316.586554] LNet: 307629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5316.588671] LNetError: 307629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5316.597796] LNet: Removed LNI 192.168.202.52@tcp [ 5316.853182] Key type .llcrypt unregistered [ 5316.854882] Key type ._llcrypt unregistered [ 5317.132836] Key type ._llcrypt registered [ 5317.133778] Key type .llcrypt registered [ 5317.321194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5317.325988] alg: No test for adler32 (adler32-zlib) [ 5318.179066] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5318.259631] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5319.839155] Key type lgssc registered [ 5320.213168] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5323.732680] Lustre: DEBUG MARKER: Iteration 9 [ 5323.858606] LustreError: 308450:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5323.858659] LustreError: 308452:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5323.863616] LustreError: 308450:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5324.913778] Lustre: Mounted lustre-client [ 5325.357724] LustreError: 308557:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0f2c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5325.360631] LustreError: 308557:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5325.391061] Lustre: Unmounted lustre-client [ 5326.329303] Key type lgssc unregistered [ 5326.450588] LNet: 308801:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5326.453037] LNetError: 308801:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5326.460456] LNet: Removed LNI 192.168.202.52@tcp [ 5326.678100] Key type .llcrypt unregistered [ 5326.679598] Key type ._llcrypt unregistered [ 5326.928331] Key type ._llcrypt registered [ 5326.929289] Key type .llcrypt registered [ 5327.125990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5327.131822] alg: No test for adler32 (adler32-zlib) [ 5327.986110] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5328.070583] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5329.655173] Key type lgssc registered [ 5330.034389] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5333.431565] Lustre: DEBUG MARKER: Iteration 10 [ 5333.556122] LustreError: 309621:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5333.556185] LustreError: 309622:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5333.561109] LustreError: 309621:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5334.621040] Lustre: Mounted lustre-client [ 5334.622265] Lustre: Skipped 1 previous similar message [ 5335.133368] LustreError: 309729:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e18000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5335.136869] LustreError: 309729:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5335.172053] Lustre: Unmounted lustre-client [ 5336.068576] Key type lgssc unregistered [ 5336.185526] LNet: 309971:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5336.188096] LNetError: 309971:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5336.195689] LNet: Removed LNI 192.168.202.52@tcp [ 5336.426096] Key type .llcrypt unregistered [ 5336.427151] Key type ._llcrypt unregistered [ 5336.761045] Key type ._llcrypt registered [ 5336.762204] Key type .llcrypt registered [ 5336.897061] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5336.902473] alg: No test for adler32 (adler32-zlib) [ 5337.754509] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5337.836979] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5339.415310] Key type lgssc registered [ 5339.985961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5343.277820] Lustre: DEBUG MARKER: Iteration 11 [ 5343.383295] LustreError: 310790:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5343.383331] LustreError: 310792:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5343.387125] LustreError: 310790:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5344.429261] Lustre: Mounted lustre-client [ 5344.889754] LustreError: 310895:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55f2b93000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5344.893116] LustreError: 310895:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5344.921839] Lustre: Unmounted lustre-client [ 5345.852315] Key type lgssc unregistered [ 5345.996881] LNet: 311137:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5346.004081] LNetError: 311137:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5346.016549] LNet: Removed LNI 192.168.202.52@tcp [ 5346.391145] Key type .llcrypt unregistered [ 5346.393143] Key type ._llcrypt unregistered [ 5346.670721] Key type ._llcrypt registered [ 5346.671650] Key type .llcrypt registered [ 5346.849952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5346.855088] alg: No test for adler32 (adler32-zlib) [ 5347.712651] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5347.791767] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5349.375185] Key type lgssc registered [ 5349.736895] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5353.001481] Lustre: DEBUG MARKER: Iteration 12 [ 5353.101538] LustreError: 311956:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5353.101864] LustreError: 311959:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5353.106306] LustreError: 311956:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5354.148941] Lustre: Mounted lustre-client [ 5354.582108] LustreError: 312066:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c563e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5354.587100] LustreError: 312066:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5354.595531] Lustre: Unmounted lustre-client [ 5355.425536] Key type lgssc unregistered [ 5355.530524] LNet: 312308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5355.532407] LNetError: 312308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5355.540728] LNet: Removed LNI 192.168.202.52@tcp [ 5355.749116] Key type .llcrypt unregistered [ 5355.750047] Key type ._llcrypt unregistered [ 5356.023550] Key type ._llcrypt registered [ 5356.028578] Key type .llcrypt registered [ 5356.219335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5356.224525] alg: No test for adler32 (adler32-zlib) [ 5357.077447] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5357.156944] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5358.743117] Key type lgssc registered [ 5359.084575] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5362.349522] Lustre: DEBUG MARKER: Iteration 13 [ 5362.445456] LustreError: 313129:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5362.445917] LustreError: 313130:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5362.450994] LustreError: 313129:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5363.504675] Lustre: Mounted lustre-client [ 5363.946933] LustreError: 313238:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e5c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5364.018402] Lustre: Unmounted lustre-client [ 5364.972776] Key type lgssc unregistered [ 5365.091503] LNet: 313481:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5365.093492] LNetError: 313481:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5365.100649] LNet: Removed LNI 192.168.202.52@tcp [ 5365.362129] Key type .llcrypt unregistered [ 5365.363109] Key type ._llcrypt unregistered [ 5365.579487] Key type ._llcrypt registered [ 5365.580382] Key type .llcrypt registered [ 5365.749232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5365.756197] alg: No test for adler32 (adler32-zlib) [ 5366.610387] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5366.692313] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5368.271159] Key type lgssc registered [ 5368.709586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5372.168694] Lustre: DEBUG MARKER: Iteration 14 [ 5372.267221] LustreError: 314300:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5372.267348] LustreError: 314301:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5372.271110] LustreError: 314300:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5373.319709] Lustre: Mounted lustre-client [ 5373.320675] Lustre: Skipped 1 previous similar message [ 5373.756881] LustreError: 314409:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0892800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5373.762182] LustreError: 314409:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5373.770563] Lustre: Unmounted lustre-client [ 5374.646856] Key type lgssc unregistered [ 5374.767311] LNet: 314652:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5374.769305] LNetError: 314652:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5374.779804] LNet: Removed LNI 192.168.202.52@tcp [ 5374.994101] Key type .llcrypt unregistered [ 5374.995096] Key type ._llcrypt unregistered [ 5375.254267] Key type ._llcrypt registered [ 5375.259489] Key type .llcrypt registered [ 5375.452112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5375.457524] alg: No test for adler32 (adler32-zlib) [ 5376.313610] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5376.392364] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5377.983156] Key type lgssc registered [ 5378.394637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5381.622337] Lustre: DEBUG MARKER: Iteration 15 [ 5381.727728] LustreError: 315473:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5381.731051] LustreError: 315474:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5381.733441] LustreError: 315473:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5382.776972] Lustre: Mounted lustre-client [ 5382.778888] Lustre: Skipped 1 previous similar message [ 5383.215859] LustreError: 315579:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d54cd539800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5383.219654] LustreError: 315579:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5383.244868] Lustre: Unmounted lustre-client [ 5384.136838] Key type lgssc unregistered [ 5384.249474] LNet: 315822:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5384.251461] LNetError: 315822:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5384.261660] LNet: Removed LNI 192.168.202.52@tcp [ 5384.467130] Key type .llcrypt unregistered [ 5384.468250] Key type ._llcrypt unregistered [ 5384.736270] Key type ._llcrypt registered [ 5384.738209] Key type .llcrypt registered [ 5384.916472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5384.923585] alg: No test for adler32 (adler32-zlib) [ 5385.781489] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5385.863294] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5387.447128] Key type lgssc registered [ 5387.814457] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5390.951936] Lustre: DEBUG MARKER: Iteration 16 [ 5391.049537] LustreError: 316642:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5391.049582] LustreError: 316643:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5391.054747] LustreError: 316642:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5392.095713] Lustre: Mounted lustre-client [ 5392.518913] LustreError: 316745:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55f5054000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5392.522362] LustreError: 316745:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5392.563780] Lustre: Unmounted lustre-client [ 5393.445480] Key type lgssc unregistered [ 5393.556390] LNet: 316988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5393.559390] LNetError: 316988:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5393.568721] LNet: Removed LNI 192.168.202.52@tcp [ 5393.770093] Key type .llcrypt unregistered [ 5393.771121] Key type ._llcrypt unregistered [ 5394.028897] Key type ._llcrypt registered [ 5394.029894] Key type .llcrypt registered [ 5394.206691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5394.211122] alg: No test for adler32 (adler32-zlib) [ 5395.055228] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5395.128977] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5396.703145] Key type lgssc registered [ 5397.068550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5400.344748] Lustre: DEBUG MARKER: Iteration 17 [ 5400.466271] LustreError: 317809:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5400.467245] LustreError: 317810:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5400.471200] LustreError: 317809:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5401.526238] Lustre: Mounted lustre-client [ 5401.978344] LustreError: 317914:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e5d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5402.020077] Lustre: Unmounted lustre-client [ 5402.021353] Lustre: Skipped 1 previous similar message [ 5402.912247] Key type lgssc unregistered [ 5403.017284] LNet: 318158:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5403.019233] LNetError: 318158:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5403.026728] LNet: Removed LNI 192.168.202.52@tcp [ 5403.264099] Key type .llcrypt unregistered [ 5403.264969] Key type ._llcrypt unregistered [ 5403.469516] Key type ._llcrypt registered [ 5403.470561] Key type .llcrypt registered [ 5403.692280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5403.697066] alg: No test for adler32 (adler32-zlib) [ 5404.549395] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5404.638189] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5406.215095] Key type lgssc registered [ 5406.561070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5409.607280] Lustre: DEBUG MARKER: Iteration 18 [ 5409.697524] LustreError: 318978:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5409.697574] LustreError: 318979:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5409.703046] LustreError: 318978:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5410.748717] Lustre: Mounted lustre-client [ 5411.191139] LustreError: 319087:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c563a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5411.194258] LustreError: 319087:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5411.209301] Lustre: Unmounted lustre-client [ 5412.061643] Key type lgssc unregistered [ 5412.170525] LNet: 319330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5412.173225] LNetError: 319330:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5412.182642] LNet: Removed LNI 192.168.202.52@tcp [ 5412.440103] Key type .llcrypt unregistered [ 5412.441147] Key type ._llcrypt unregistered [ 5412.665598] Key type ._llcrypt registered [ 5412.666580] Key type .llcrypt registered [ 5412.899255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5412.906669] alg: No test for adler32 (adler32-zlib) [ 5413.755219] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5413.834384] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5415.415146] Key type lgssc registered [ 5415.818696] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5419.502862] Lustre: DEBUG MARKER: Iteration 19 [ 5419.617703] LustreError: 320151:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5419.617735] LustreError: 320152:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5419.621868] LustreError: 320151:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5420.669141] Lustre: Mounted lustre-client [ 5421.156925] LustreError: 320255:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c35a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5421.160027] LustreError: 320255:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5421.200865] Lustre: Unmounted lustre-client [ 5422.104524] Key type lgssc unregistered [ 5422.213380] LNet: 320499:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5422.216305] LNetError: 320499:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5422.223626] LNet: Removed LNI 192.168.202.52@tcp [ 5422.468149] Key type .llcrypt unregistered [ 5422.469253] Key type ._llcrypt unregistered [ 5422.783718] Key type ._llcrypt registered [ 5422.784702] Key type .llcrypt registered [ 5422.936472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5422.944051] alg: No test for adler32 (adler32-zlib) [ 5423.792997] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5423.866190] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5425.447170] Key type lgssc registered [ 5425.828966] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5429.190098] Lustre: DEBUG MARKER: Iteration 20 [ 5429.312118] LustreError: 321319:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5429.312240] LustreError: 321320:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5429.318175] LustreError: 321319:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5430.371460] Lustre: Mounted lustre-client [ 5430.372366] Lustre: Skipped 1 previous similar message [ 5430.799992] LustreError: 321421:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c999e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5430.803010] LustreError: 321421:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5430.830109] Lustre: Unmounted lustre-client [ 5431.704662] Key type lgssc unregistered [ 5431.818486] LNet: 321664:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5431.820784] LNetError: 321664:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5431.831737] LNet: Removed LNI 192.168.202.52@tcp [ 5432.076154] Key type .llcrypt unregistered [ 5432.077202] Key type ._llcrypt unregistered [ 5432.350404] Key type ._llcrypt registered [ 5432.351527] Key type .llcrypt registered [ 5432.540910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5432.545670] alg: No test for adler32 (adler32-zlib) [ 5433.405275] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5433.484417] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5435.063137] Key type lgssc registered [ 5435.461127] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5438.838756] Lustre: DEBUG MARKER: Iteration 21 [ 5438.940382] LustreError: 322485:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5438.940442] LustreError: 322486:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5438.946958] LustreError: 322485:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5440.001325] Lustre: Mounted lustre-client [ 5440.485850] LustreError: 322592:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55cf862800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5440.491422] LustreError: 322592:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5440.504738] Lustre: Unmounted lustre-client [ 5441.362518] Key type lgssc unregistered [ 5441.464515] LNet: 322835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5441.466704] LNetError: 322835:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5441.473762] LNet: Removed LNI 192.168.202.52@tcp [ 5441.699097] Key type .llcrypt unregistered [ 5441.700084] Key type ._llcrypt unregistered [ 5441.975601] Key type ._llcrypt registered [ 5441.976510] Key type .llcrypt registered [ 5442.142803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5442.147751] alg: No test for adler32 (adler32-zlib) [ 5443.005705] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5443.086254] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5444.671138] Key type lgssc registered [ 5445.054358] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5448.386404] Lustre: DEBUG MARKER: Iteration 22 [ 5448.486907] LustreError: 323657:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5448.486921] LustreError: 323658:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5448.491032] LustreError: 323657:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5449.536554] Lustre: Mounted lustre-client [ 5449.538330] Lustre: Skipped 1 previous similar message [ 5449.989406] LustreError: 323758:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55faabb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5449.992552] LustreError: 323758:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5450.040896] Lustre: Unmounted lustre-client [ 5450.852270] Key type lgssc unregistered [ 5450.971539] LNet: 324001:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5450.973563] LNetError: 324001:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5450.981735] LNet: Removed LNI 192.168.202.52@tcp [ 5451.208080] Key type .llcrypt unregistered [ 5451.209215] Key type ._llcrypt unregistered [ 5451.481982] Key type ._llcrypt registered [ 5451.483062] Key type .llcrypt registered [ 5451.658480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5451.662756] alg: No test for adler32 (adler32-zlib) [ 5452.521111] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5452.602173] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5454.183113] Key type lgssc registered [ 5454.595606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5457.818904] Lustre: DEBUG MARKER: Iteration 23 [ 5457.930191] LustreError: 324823:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5457.930236] LustreError: 324822:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5457.934371] LustreError: 324823:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5458.980373] Lustre: Mounted lustre-client [ 5458.981334] Lustre: Skipped 1 previous similar message [ 5459.416987] LustreError: 324925:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c9998800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5459.468061] Lustre: Unmounted lustre-client [ 5460.402660] Key type lgssc unregistered [ 5460.509483] LNet: 325168:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5460.511666] LNetError: 325168:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5460.522091] LNet: Removed LNI 192.168.202.52@tcp [ 5460.821101] Key type .llcrypt unregistered [ 5460.821975] Key type ._llcrypt unregistered [ 5461.151230] Key type ._llcrypt registered [ 5461.152439] Key type .llcrypt registered [ 5461.297856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5461.303213] alg: No test for adler32 (adler32-zlib) [ 5462.154477] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5462.235068] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5463.815128] Key type lgssc registered [ 5464.193194] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5467.640759] Lustre: DEBUG MARKER: Iteration 24 [ 5467.767537] LustreError: 325989:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5467.767586] LustreError: 325990:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5467.771817] LustreError: 325989:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5468.827942] Lustre: Mounted lustre-client [ 5468.829911] Lustre: Skipped 1 previous similar message [ 5469.439382] LustreError: 326098:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7ead800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5469.442797] LustreError: 326098:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5469.493149] Lustre: Unmounted lustre-client [ 5470.493985] Key type lgssc unregistered [ 5470.619069] LNet: 326340:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5470.622640] LNetError: 326340:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5470.634659] LNet: Removed LNI 192.168.202.52@tcp [ 5470.901145] Key type .llcrypt unregistered [ 5470.902229] Key type ._llcrypt unregistered [ 5471.275573] Key type ._llcrypt registered [ 5471.276663] Key type .llcrypt registered [ 5471.459846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5471.464957] alg: No test for adler32 (adler32-zlib) [ 5472.339894] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5472.434558] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5474.023109] Key type lgssc registered [ 5474.408664] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5477.719376] Lustre: DEBUG MARKER: Iteration 25 [ 5477.815881] LustreError: 327161:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5477.815915] LustreError: 327162:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5477.819602] LustreError: 327161:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5478.858915] Lustre: Mounted lustre-client [ 5479.299943] LustreError: 327264:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0f2e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5479.303447] LustreError: 327264:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5479.349104] Lustre: Unmounted lustre-client [ 5479.350248] Lustre: Skipped 1 previous similar message [ 5480.278750] Key type lgssc unregistered [ 5480.396515] LNet: 327507:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5480.398457] LNetError: 327507:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5480.408620] LNet: Removed LNI 192.168.202.52@tcp [ 5480.634087] Key type .llcrypt unregistered [ 5480.635613] Key type ._llcrypt unregistered [ 5480.891562] Key type ._llcrypt registered [ 5480.892486] Key type .llcrypt registered [ 5481.076782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5481.081432] alg: No test for adler32 (adler32-zlib) [ 5481.934526] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5482.025557] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5483.607161] Key type lgssc registered [ 5483.990050] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5487.373772] Lustre: DEBUG MARKER: Iteration 26 [ 5487.471249] LustreError: 328328:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5487.471580] LustreError: 328329:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5487.475337] LustreError: 328328:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5488.522842] Lustre: Mounted lustre-client [ 5488.972962] LustreError: 328437:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c999d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5489.000275] Lustre: Unmounted lustre-client [ 5489.944547] Key type lgssc unregistered [ 5490.059419] LNet: 328679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5490.061515] LNetError: 328679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5490.068760] LNet: Removed LNI 192.168.202.52@tcp [ 5490.330087] Key type .llcrypt unregistered [ 5490.331066] Key type ._llcrypt unregistered [ 5490.647549] Key type ._llcrypt registered [ 5490.658161] Key type .llcrypt registered [ 5490.819341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5490.827279] alg: No test for adler32 (adler32-zlib) [ 5491.681070] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5491.766345] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5493.343136] Key type lgssc registered [ 5493.716273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5497.007687] Lustre: DEBUG MARKER: Iteration 27 [ 5497.112393] LustreError: 329499:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5497.112407] LustreError: 329500:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5497.117083] LustreError: 329499:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5498.166049] Lustre: Mounted lustre-client [ 5498.618833] LustreError: 329601:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5498.622449] LustreError: 329601:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5498.643109] Lustre: Unmounted lustre-client [ 5499.578475] Key type lgssc unregistered [ 5499.701431] LNet: 329844:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5499.703552] LNetError: 329844:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5499.710645] LNet: Removed LNI 192.168.202.52@tcp [ 5499.940086] Key type .llcrypt unregistered [ 5499.941113] Key type ._llcrypt unregistered [ 5500.210621] Key type ._llcrypt registered [ 5500.212731] Key type .llcrypt registered [ 5500.384823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5500.390961] alg: No test for adler32 (adler32-zlib) [ 5501.253018] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5501.339377] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5502.927100] Key type lgssc registered [ 5503.316869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5506.823445] Lustre: DEBUG MARKER: Iteration 28 [ 5506.937550] LustreError: 330664:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5506.937996] LustreError: 330665:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5506.942229] LustreError: 330664:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5507.993817] Lustre: Mounted lustre-client [ 5508.535828] LustreError: 330770:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55ee9e1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5508.539389] LustreError: 330770:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5508.555163] Lustre: Unmounted lustre-client [ 5509.543734] Key type lgssc unregistered [ 5509.670302] LNet: 331013:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5509.672619] LNetError: 331013:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5509.679995] LNet: Removed LNI 192.168.202.52@tcp [ 5509.929130] Key type .llcrypt unregistered [ 5509.930037] Key type ._llcrypt unregistered [ 5510.251873] Key type ._llcrypt registered [ 5510.255055] Key type .llcrypt registered [ 5510.501195] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5510.507420] alg: No test for adler32 (adler32-zlib) [ 5511.368895] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5511.457535] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5513.047155] Key type lgssc registered [ 5513.520546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5516.906596] Lustre: DEBUG MARKER: Iteration 29 [ 5517.010958] LustreError: 331833:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5517.011058] LustreError: 331834:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5517.015524] LustreError: 331833:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5518.056508] Lustre: Mounted lustre-client [ 5518.524962] LustreError: 331939:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c5a86000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5518.562803] Lustre: Unmounted lustre-client [ 5519.494140] Key type lgssc unregistered [ 5519.606423] LNet: 332183:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5519.608650] LNetError: 332183:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5519.616566] LNet: Removed LNI 192.168.202.52@tcp [ 5519.859104] Key type .llcrypt unregistered [ 5519.860354] Key type ._llcrypt unregistered [ 5520.116699] Key type ._llcrypt registered [ 5520.117758] Key type .llcrypt registered [ 5520.326360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5520.331200] alg: No test for adler32 (adler32-zlib) [ 5521.190553] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5521.289868] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5522.879120] Key type lgssc registered [ 5523.300414] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5526.669459] Lustre: DEBUG MARKER: Iteration 30 [ 5526.768036] LustreError: 333004:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5526.768354] LustreError: 333005:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5526.772592] LustreError: 333004:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5527.818482] Lustre: Mounted lustre-client [ 5528.270214] LustreError: 333112:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d02f2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5528.273454] LustreError: 333112:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5528.325704] Lustre: Unmounted lustre-client [ 5528.326648] Lustre: Skipped 1 previous similar message [ 5529.462806] Key type lgssc unregistered [ 5529.595809] LNet: 333354:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5529.598869] LNetError: 333354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5529.610435] LNet: Removed LNI 192.168.202.52@tcp [ 5529.855142] Key type .llcrypt unregistered [ 5529.857272] Key type ._llcrypt unregistered [ 5530.113913] Key type ._llcrypt registered [ 5530.114912] Key type .llcrypt registered [ 5530.304882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5530.309221] alg: No test for adler32 (adler32-zlib) [ 5531.163802] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5531.242833] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5532.823151] Key type lgssc registered [ 5533.193548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5536.431500] Lustre: DEBUG MARKER: Iteration 31 [ 5536.552390] LustreError: 334175:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5536.552472] LustreError: 334176:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5536.558805] LustreError: 334175:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5537.605222] Lustre: Mounted lustre-client [ 5537.606569] Lustre: Skipped 1 previous similar message [ 5538.106173] LustreError: 334283:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d143a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5538.109199] LustreError: 334283:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5538.164228] Lustre: Unmounted lustre-client [ 5538.165201] Lustre: Skipped 1 previous similar message [ 5539.244827] Key type lgssc unregistered [ 5539.368742] LNet: 334526:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5539.371560] LNetError: 334526:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5539.382142] LNet: Removed LNI 192.168.202.52@tcp [ 5539.640109] Key type .llcrypt unregistered [ 5539.640977] Key type ._llcrypt unregistered [ 5539.945715] Key type ._llcrypt registered [ 5539.964991] Key type .llcrypt registered [ 5540.192733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5540.198752] alg: No test for adler32 (adler32-zlib) [ 5541.071177] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5541.153867] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5542.743205] Key type lgssc registered [ 5543.216455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5546.940909] Lustre: DEBUG MARKER: Iteration 32 [ 5547.037031] LustreError: 335347:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5547.038739] LustreError: 335348:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5547.042093] LustreError: 335347:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5548.090678] Lustre: Mounted lustre-client [ 5548.537805] LustreError: 335451:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e40800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5548.603514] Lustre: Unmounted lustre-client [ 5549.580262] Key type lgssc unregistered [ 5549.687538] LNet: 335693:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5549.689796] LNetError: 335693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5549.697747] LNet: Removed LNI 192.168.202.52@tcp [ 5549.943115] Key type .llcrypt unregistered [ 5549.944391] Key type ._llcrypt unregistered [ 5550.214434] Key type ._llcrypt registered [ 5550.216211] Key type .llcrypt registered [ 5550.393743] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5550.398897] alg: No test for adler32 (adler32-zlib) [ 5551.249989] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5551.340312] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5552.927181] Key type lgssc registered [ 5553.301192] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5556.457647] Lustre: DEBUG MARKER: Iteration 33 [ 5556.549630] LustreError: 336512:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5556.552228] LustreError: 336514:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5556.554031] LustreError: 336512:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5557.605162] Lustre: Mounted lustre-client [ 5557.606187] Lustre: Skipped 1 previous similar message [ 5558.068402] LustreError: 336619:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d1506000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5558.074437] LustreError: 336619:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5558.126100] Lustre: Unmounted lustre-client [ 5559.063143] Key type lgssc unregistered [ 5559.172514] LNet: 336862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5559.174563] LNetError: 336862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5559.182713] LNet: Removed LNI 192.168.202.52@tcp [ 5559.415165] Key type .llcrypt unregistered [ 5559.416850] Key type ._llcrypt unregistered [ 5559.703091] Key type ._llcrypt registered [ 5559.704327] Key type .llcrypt registered [ 5559.880964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5559.886099] alg: No test for adler32 (adler32-zlib) [ 5560.742695] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5560.825338] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5562.399101] Key type lgssc registered [ 5562.767100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5566.010365] Lustre: DEBUG MARKER: Iteration 34 [ 5566.106960] LustreError: 337684:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5566.109120] LustreError: 337685:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5566.112071] LustreError: 337684:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5567.159106] Lustre: Mounted lustre-client [ 5567.161251] Lustre: Skipped 1 previous similar message [ 5567.593043] LustreError: 337794:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85af800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5567.598371] LustreError: 337794:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5567.606315] Lustre: Unmounted lustre-client [ 5568.634279] Key type lgssc unregistered [ 5568.762616] LNet: 338037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5568.765535] LNetError: 338037:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5568.774534] LNet: Removed LNI 192.168.202.52@tcp [ 5569.055109] Key type .llcrypt unregistered [ 5569.056252] Key type ._llcrypt unregistered [ 5569.280395] Key type ._llcrypt registered [ 5569.281366] Key type .llcrypt registered [ 5569.476269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5569.480958] alg: No test for adler32 (adler32-zlib) [ 5570.334888] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5570.411964] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5571.991204] Key type lgssc registered [ 5572.352019] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5575.747599] Lustre: DEBUG MARKER: Iteration 35 [ 5575.848344] LustreError: 338856:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5575.848390] LustreError: 338858:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5575.852025] LustreError: 338856:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5576.898283] Lustre: Mounted lustre-client [ 5576.900421] Lustre: Skipped 1 previous similar message [ 5577.339812] LustreError: 338965:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c5187000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5577.345015] LustreError: 338965:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5577.353114] Lustre: Unmounted lustre-client [ 5578.402230] Key type lgssc unregistered [ 5578.516618] LNet: 339208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5578.519616] LNetError: 339208:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5578.527974] LNet: Removed LNI 192.168.202.52@tcp [ 5578.806154] Key type .llcrypt unregistered [ 5578.807065] Key type ._llcrypt unregistered [ 5579.110597] Key type ._llcrypt registered [ 5579.113149] Key type .llcrypt registered [ 5579.352222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5579.357359] alg: No test for adler32 (adler32-zlib) [ 5580.221607] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5580.313874] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5581.903143] Key type lgssc registered [ 5582.307291] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5585.697417] Lustre: DEBUG MARKER: Iteration 36 [ 5585.788146] LustreError: 340030:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5585.788191] LustreError: 340031:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5585.793931] LustreError: 340030:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5586.849596] Lustre: Mounted lustre-client [ 5586.850489] Lustre: Skipped 1 previous similar message [ 5587.364805] LustreError: 340138:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c85ad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5587.368429] LustreError: 340138:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5587.385392] Lustre: Unmounted lustre-client [ 5588.363902] Key type lgssc unregistered [ 5588.482811] LNet: 340381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5588.487127] LNetError: 340381:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5588.497296] LNet: Removed LNI 192.168.202.52@tcp [ 5588.744155] Key type .llcrypt unregistered [ 5588.745967] Key type ._llcrypt unregistered [ 5589.002110] Key type ._llcrypt registered [ 5589.003381] Key type .llcrypt registered [ 5589.255909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5589.275362] alg: No test for adler32 (adler32-zlib) [ 5590.128288] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5590.207502] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5591.791178] Key type lgssc registered [ 5592.195483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5595.706953] Lustre: DEBUG MARKER: Iteration 37 [ 5595.839071] LustreError: 341204:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5595.839099] LustreError: 341203:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5595.844046] LustreError: 341204:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5596.909491] Lustre: Mounted lustre-client [ 5596.910970] Lustre: Skipped 1 previous similar message [ 5597.476620] LustreError: 341311:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d5d46000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5597.510918] Lustre: Unmounted lustre-client [ 5598.655913] Key type lgssc unregistered [ 5598.780892] LNet: 341555:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5598.784601] LNetError: 341555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5598.796191] LNet: Removed LNI 192.168.202.52@tcp [ 5599.073095] Key type .llcrypt unregistered [ 5599.074099] Key type ._llcrypt unregistered [ 5599.409363] Key type ._llcrypt registered [ 5599.410322] Key type .llcrypt registered [ 5599.535069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5599.539753] alg: No test for adler32 (adler32-zlib) [ 5600.403989] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5600.486986] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5602.063086] Key type lgssc registered [ 5602.439941] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5605.471293] Lustre: DEBUG MARKER: Iteration 38 [ 5605.569228] LustreError: 342375:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5605.569263] LustreError: 342376:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5605.574955] LustreError: 342375:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5606.612843] Lustre: Mounted lustre-client [ 5607.030964] LustreError: 342475:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c9998800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5607.034218] LustreError: 342475:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5607.047102] Lustre: Unmounted lustre-client [ 5607.888259] Key type lgssc unregistered [ 5607.997459] LNet: 342718:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5607.999402] LNetError: 342718:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5608.007717] LNet: Removed LNI 192.168.202.52@tcp [ 5608.218086] Key type .llcrypt unregistered [ 5608.218988] Key type ._llcrypt unregistered [ 5608.451223] Key type ._llcrypt registered [ 5608.452152] Key type .llcrypt registered [ 5608.624530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5608.636735] alg: No test for adler32 (adler32-zlib) [ 5609.480530] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5609.554361] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5611.135146] Key type lgssc registered [ 5611.507900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5614.735176] Lustre: DEBUG MARKER: Iteration 39 [ 5614.857055] LustreError: 343538:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5614.858362] LustreError: 343539:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5614.861741] LustreError: 343538:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5615.912939] Lustre: Mounted lustre-client [ 5615.914506] Lustre: Skipped 1 previous similar message [ 5616.347958] LustreError: 343644:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e5f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5616.352630] LustreError: 343644:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5616.367935] Lustre: Unmounted lustre-client [ 5617.364846] Key type lgssc unregistered [ 5617.482551] LNet: 343886:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5617.485282] LNetError: 343886:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5617.492796] LNet: Removed LNI 192.168.202.52@tcp [ 5617.724133] Key type .llcrypt unregistered [ 5617.726322] Key type ._llcrypt unregistered [ 5617.986446] Key type ._llcrypt registered [ 5617.987434] Key type .llcrypt registered [ 5618.194058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5618.200083] alg: No test for adler32 (adler32-zlib) [ 5619.060551] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5619.137352] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5620.719147] Key type lgssc registered [ 5621.143078] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5624.595687] Lustre: DEBUG MARKER: Iteration 40 [ 5624.689717] LustreError: 344706:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5624.689981] LustreError: 344707:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5624.694646] LustreError: 344706:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5625.741228] Lustre: Mounted lustre-client [ 5626.191541] LustreError: 344810:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55f104c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5626.194540] LustreError: 344810:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5626.219199] Lustre: Unmounted lustre-client [ 5627.227617] Key type lgssc unregistered [ 5627.348834] LNet: 345054:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5627.351423] LNetError: 345054:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5627.361462] LNet: Removed LNI 192.168.202.52@tcp [ 5627.625116] Key type .llcrypt unregistered [ 5627.626257] Key type ._llcrypt unregistered [ 5627.921117] Key type ._llcrypt registered [ 5627.922706] Key type .llcrypt registered [ 5628.216149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5628.222073] alg: No test for adler32 (adler32-zlib) [ 5629.146862] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5629.253895] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5630.855194] Key type lgssc registered [ 5631.233901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5634.512706] Lustre: DEBUG MARKER: Iteration 41 [ 5634.650577] LustreError: 345875:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5634.651816] LustreError: 345876:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5634.655418] LustreError: 345875:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5635.705691] Lustre: Mounted lustre-client [ 5636.146899] LustreError: 345984:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55f5f02800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5636.166884] Lustre: Unmounted lustre-client [ 5637.070770] Key type lgssc unregistered [ 5637.190437] LNet: 346226:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5637.192418] LNetError: 346226:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5637.201745] LNet: Removed LNI 192.168.202.52@tcp [ 5637.432088] Key type .llcrypt unregistered [ 5637.433466] Key type ._llcrypt unregistered [ 5637.707421] Key type ._llcrypt registered [ 5637.708383] Key type .llcrypt registered [ 5637.875918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5637.880560] alg: No test for adler32 (adler32-zlib) [ 5638.728486] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5638.807741] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5640.391147] Key type lgssc registered [ 5640.962302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5644.174173] Lustre: DEBUG MARKER: Iteration 42 [ 5644.299684] LustreError: 347046:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5644.299724] LustreError: 347047:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5644.304652] LustreError: 347046:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5645.350174] Lustre: Mounted lustre-client [ 5645.795793] LustreError: 347155:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0f28800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5645.799284] LustreError: 347155:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5645.827816] Lustre: Unmounted lustre-client [ 5646.924853] Key type lgssc unregistered [ 5647.066608] LNet: 347398:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5647.074047] LNetError: 347398:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5647.086126] LNet: Removed LNI 192.168.202.52@tcp [ 5647.452218] Key type .llcrypt unregistered [ 5647.455445] Key type ._llcrypt unregistered [ 5647.777625] Key type ._llcrypt registered [ 5647.779055] Key type .llcrypt registered [ 5647.942957] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5647.947581] alg: No test for adler32 (adler32-zlib) [ 5648.800631] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5648.898647] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5650.495204] Key type lgssc registered [ 5650.957441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5654.692471] Lustre: DEBUG MARKER: Iteration 43 [ 5654.818868] LustreError: 348218:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5654.818928] LustreError: 348220:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5654.823385] LustreError: 348218:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5655.873715] Lustre: Mounted lustre-client [ 5656.460232] LustreError: 348326:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d03df800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5656.463811] LustreError: 348326:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5656.480250] Lustre: Unmounted lustre-client [ 5657.495750] Key type lgssc unregistered [ 5657.607482] LNet: 348570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5657.609691] LNetError: 348570:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5657.621056] LNet: Removed LNI 192.168.202.52@tcp [ 5657.866112] Key type .llcrypt unregistered [ 5657.867188] Key type ._llcrypt unregistered [ 5658.283522] Key type ._llcrypt registered [ 5658.284486] Key type .llcrypt registered [ 5658.424625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5658.429790] alg: No test for adler32 (adler32-zlib) [ 5659.290752] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5659.372150] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5660.959154] Key type lgssc registered [ 5661.379361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5664.789893] Lustre: DEBUG MARKER: Iteration 44 [ 5664.886829] LustreError: 349391:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5664.887813] LustreError: 349392:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5664.891660] LustreError: 349391:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5665.938625] Lustre: Mounted lustre-client [ 5665.939665] Lustre: Skipped 1 previous similar message [ 5666.382204] LustreError: 349498:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c652d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5666.385322] LustreError: 349498:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5666.439075] Lustre: Unmounted lustre-client [ 5667.509389] Key type lgssc unregistered [ 5667.654551] LNet: 349740:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5667.659495] LNetError: 349740:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5667.671480] LNet: Removed LNI 192.168.202.52@tcp [ 5667.982139] Key type .llcrypt unregistered [ 5667.984562] Key type ._llcrypt unregistered [ 5668.263049] Key type ._llcrypt registered [ 5668.264345] Key type .llcrypt registered [ 5668.439359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5668.445712] alg: No test for adler32 (adler32-zlib) [ 5669.322988] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5669.418959] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5671.007217] Key type lgssc registered [ 5671.347656] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5674.632632] Lustre: DEBUG MARKER: Iteration 45 [ 5674.731654] LustreError: 350559:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5674.731714] LustreError: 350561:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5674.737078] LustreError: 350559:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5675.794796] Lustre: Mounted lustre-client [ 5675.795682] Lustre: Skipped 1 previous similar message [ 5676.227044] LustreError: 350663:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c2bbb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5676.230227] LustreError: 350663:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5676.273992] Lustre: Unmounted lustre-client [ 5676.275817] Lustre: Skipped 1 previous similar message [ 5677.172508] Key type lgssc unregistered [ 5677.277462] LNet: 350906:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5677.279961] LNetError: 350906:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5677.287754] LNet: Removed LNI 192.168.202.52@tcp [ 5677.525102] Key type .llcrypt unregistered [ 5677.526306] Key type ._llcrypt unregistered [ 5677.768286] Key type ._llcrypt registered [ 5677.774779] Key type .llcrypt registered [ 5677.957051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5677.963081] alg: No test for adler32 (adler32-zlib) [ 5678.815701] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5678.895625] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5680.479138] Key type lgssc registered [ 5680.838690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5684.287329] Lustre: DEBUG MARKER: Iteration 46 [ 5684.400160] LustreError: 351729:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5684.400197] LustreError: 351728:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5684.404112] LustreError: 351729:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5685.449771] Lustre: Mounted lustre-client [ 5685.897752] LustreError: 351832:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55ca269000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5685.901293] LustreError: 351832:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5685.919414] Lustre: Unmounted lustre-client [ 5686.838941] Key type lgssc unregistered [ 5686.939555] LNet: 352074:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5686.943186] LNetError: 352074:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5686.949641] LNet: Removed LNI 192.168.202.52@tcp [ 5687.173101] Key type .llcrypt unregistered [ 5687.174217] Key type ._llcrypt unregistered [ 5687.489655] Key type ._llcrypt registered [ 5687.490635] Key type .llcrypt registered [ 5687.627611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5687.633884] alg: No test for adler32 (adler32-zlib) [ 5688.492387] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5688.585905] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5690.183147] Key type lgssc registered [ 5690.553514] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5694.037276] Lustre: DEBUG MARKER: Iteration 47 [ 5694.140496] LustreError: 352895:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5694.140527] LustreError: 352896:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5694.144909] LustreError: 352895:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5695.191647] Lustre: Mounted lustre-client [ 5695.193104] Lustre: Skipped 1 previous similar message [ 5695.630365] LustreError: 352999:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d54cd538000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5695.634840] LustreError: 352999:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5695.647614] Lustre: Unmounted lustre-client [ 5696.545950] Key type lgssc unregistered [ 5696.656465] LNet: 353242:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5696.658427] LNetError: 353242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5696.666782] LNet: Removed LNI 192.168.202.52@tcp [ 5696.896087] Key type .llcrypt unregistered [ 5696.897375] Key type ._llcrypt unregistered [ 5697.125288] Key type ._llcrypt registered [ 5697.126214] Key type .llcrypt registered [ 5697.314742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5697.322705] alg: No test for adler32 (adler32-zlib) [ 5698.176570] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5698.262353] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5699.847183] Key type lgssc registered [ 5700.231822] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5703.548577] Lustre: DEBUG MARKER: Iteration 48 [ 5703.666204] LustreError: 354061:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5703.666236] LustreError: 354062:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5703.671520] LustreError: 354061:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5704.719611] Lustre: Mounted lustre-client [ 5704.721128] Lustre: Skipped 1 previous similar message [ 5705.156265] LustreError: 354168:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0f2c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5705.160705] LustreError: 354168:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5705.196403] Lustre: Unmounted lustre-client [ 5706.175870] Key type lgssc unregistered [ 5706.305813] LNet: 354411:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5706.308393] LNetError: 354411:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5706.317192] LNet: Removed LNI 192.168.202.52@tcp [ 5706.568128] Key type .llcrypt unregistered [ 5706.569342] Key type ._llcrypt unregistered [ 5706.896316] Key type ._llcrypt registered [ 5706.897631] Key type .llcrypt registered [ 5707.125444] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5707.131180] alg: No test for adler32 (adler32-zlib) [ 5707.991038] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5708.081746] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5709.663134] Key type lgssc registered [ 5710.020540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5713.754450] Lustre: DEBUG MARKER: Iteration 49 [ 5713.856284] LustreError: 355233:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5713.856325] LustreError: 355234:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5713.860405] LustreError: 355233:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5714.909977] Lustre: Mounted lustre-client [ 5715.345255] LustreError: 355340:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55e68ef000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5715.407470] Lustre: Unmounted lustre-client [ 5716.339209] Key type lgssc unregistered [ 5716.461511] LNet: 355584:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5716.463679] LNetError: 355584:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5716.473566] LNet: Removed LNI 192.168.202.52@tcp [ 5716.710098] Key type .llcrypt unregistered [ 5716.710986] Key type ._llcrypt unregistered [ 5717.165324] Key type ._llcrypt registered [ 5717.166582] Key type .llcrypt registered [ 5717.274281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5717.279327] alg: No test for adler32 (adler32-zlib) [ 5718.148671] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5718.240387] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5719.831172] Key type lgssc registered [ 5720.201241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5723.555512] Lustre: DEBUG MARKER: Iteration 50 [ 5723.670848] LustreError: 356405:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 5723.670908] LustreError: 356406:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 5723.676235] LustreError: 356405:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5724.723970] Lustre: Mounted lustre-client [ 5724.725735] Lustre: Skipped 1 previous similar message [ 5725.169752] LustreError: 356510:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55c7e5e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5725.173156] LustreError: 356510:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 5725.200117] Lustre: Unmounted lustre-client [ 5726.132540] Key type lgssc unregistered [ 5726.248677] LNet: 356753:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5726.251972] LNetError: 356753:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5726.262708] LNet: Removed LNI 192.168.202.52@tcp [ 5726.526107] Key type .llcrypt unregistered [ 5726.527154] Key type ._llcrypt unregistered [ 5726.845832] Key type ._llcrypt registered [ 5726.846913] Key type .llcrypt registered [ 5727.093984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5727.099185] alg: No test for adler32 (adler32-zlib) [ 5727.961270] Lustre: Lustre: Build Version: 2.17.51_75_gf89bdc1 [ 5728.046385] LNet: Added LNI 192.168.202.52@tcp [8/256/0/180] [ 5729.631193] Key type lgssc registered [ 5730.087507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5734.075584] Lustre: Mounted lustre-client [ 5736.329854] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:15:43 (1777428943) [ 5753.311170] Lustre: 358086:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777428944/real 1777428944] req@ffff9d55d5f1ad80 x1863769322955520/t0(0) o36->lustre-MDT0000-mdc-ffff9d55d03c1800@192.168.202.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1777428960 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5753.318053] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5753.328160] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5768.671160] Lustre: 358086:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777428960/real 1777428960] req@ffff9d55d5f1ad80 x1863769322955520/t0(0) o36->lustre-MDT0000-mdc-ffff9d55d03c1800@192.168.202.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1777428976 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5768.681966] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5768.692506] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5785.055096] Lustre: 358086:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777428976/real 1777428976] req@ffff9d55d5f1ad80 x1863769322955520/t0(0) o36->lustre-MDT0000-mdc-ffff9d55d03c1800@192.168.202.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1777428992 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5785.061757] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5785.069736] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5801.439165] Lustre: 358086:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777428992/real 1777428992] req@ffff9d55d5f1ad80 x1863769322955520/t0(0) o36->lustre-MDT0000-mdc-ffff9d55d03c1800@192.168.202.152@tcp:12/10 lens 496/440 e 0 to 1 dl 1777429008 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5801.450412] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection to lustre-MDT0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5801.462473] Lustre: lustre-MDT0000-mdc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5801.951904] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:16:49 (1777429009) [ 5802.428529] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5802.968536] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:16:49 (1777429009) [ 5803.452441] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5803.988810] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:16:51 (1777429011) [ 5806.143753] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:16:53 (1777429013) [ 5806.621498] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5807.150570] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:16:54 (1777429014) [ 5807.615382] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5808.132868] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:16:55 (1777429015) [ 5808.662407] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5809.271832] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 22:16:56 (1777429016) [ 5809.341858] LustreError: 360737:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 5811.423108] LustreError: 360737:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 5813.444099] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:17:00 (1777429020) [ 5830.623107] Lustre: 356946:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777429022/real 1777429022] req@ffff9d55e692a680 x1863769322985088/t0(0) o4->lustre-OST0000-osc-ffff9d55d03c1800@192.168.202.152@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777429038 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5830.623150] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5830.629562] Lustre: 356946:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5830.641242] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5847.007142] Lustre: 356947:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777429038/real 1777429038] req@ffff9d55d5f19180 x1863769322987776/t0(0) o4->lustre-OST0000-osc-ffff9d55d03c1800@192.168.202.152@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777429054 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5847.007171] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5847.017342] Lustre: 356947:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5847.031035] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5863.391110] Lustre: 356947:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1777429054/real 1777429054] req@ffff9d55d5f19180 x1863769322987776/t0(0) o4->lustre-OST0000-osc-ffff9d55d03c1800@192.168.202.152@tcp:6/4 lens 4584/448 e 0 to 1 dl 1777429070 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5863.391169] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection to lustre-OST0000 (at 192.168.202.152@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5863.398593] Lustre: 356947:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5863.411506] Lustre: lustre-OST0000-osc-ffff9d55d03c1800: Connection restored to 192.168.202.152@tcp (at 192.168.202.152@tcp) [ 5878.796409] Lustre: DEBUG MARKER: oleg252-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d55d03c1800.ost_server_uuid 50 [ 5879.299310] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d55d03c1800.ost_server_uuid in FULL state after 0 sec [ 5879.883841] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5880.435298] Lustre: DEBUG MARKER: == sanityn test complete, duration 5650 sec ============== 22:18:07 (1777429087) [ 5880.943830] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:18:07 (1777429087) === [ 5925.071222] LustreError: 362732:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d0f29000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5925.083974] Lustre: Unmounted lustre-client [ 5926.276395] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:18:53 (1777429133) === [ 5926.571333] LustreError: 363032:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff9d55d03c1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5926.574303] LustreError: 363032:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5926.593996] Lustre: Unmounted lustre-client [ 5938.049911] Key type lgssc unregistered [ 5938.159542] LNet: 363511:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5938.162567] LNetError: 363511:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5938.172508] LNet: Removed LNI 192.168.202.52@tcp [ 5938.412096] Key type .llcrypt unregistered [ 5938.413085] Key type ._llcrypt unregistered