[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 444105971 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.002370] x2apic enabled [ 0.004004] Switched APIC routing to physical x2apic. [ 0.005017] 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.008027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.011037] LSM: Security Framework initializing [ 0.012071] Yama: becoming mindful. [ 0.013036] SELinux: Initializing. [ 0.014078] *** VALIDATE selinux *** [ 0.022000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025176] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026124] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027124] *** VALIDATE tmpfs *** [ 0.029243] *** VALIDATE proc *** [ 0.030253] *** VALIDATE cgroup *** [ 0.031008] *** VALIDATE cgroup2 *** [ 0.032248] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033131] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035034] Spectre V2 : User space: Vulnerable [ 0.036011] Speculative Store Bypass: Vulnerable [ 0.038705] debug: unmapping init [mem 0xffffffffa7a59000-0xffffffffa7a60fff] [ 0.040913] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041774] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042022] ... version: 2 [ 0.043013] ... bit width: 48 [ 0.044014] ... generic registers: 4 [ 0.045016] ... value mask: 0000ffffffffffff [ 0.046018] ... max period: 00007fffffffffff [ 0.047017] ... fixed-purpose events: 3 [ 0.047838] ... event mask: 000000070000000f [ 0.048259] rcu: Hierarchical SRCU implementation. [ 0.050310] smp: Bringing up secondary CPUs ... [ 0.051591] x86: Booting SMP configuration: [ 0.052024] .... node #0, CPUs: #1 #2 #3 [ 0.059032] smp: Brought up 1 node, 4 CPUs [ 0.061018] smpboot: Max logical packages: 1 [ 0.062017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.282418] node 0 deferred pages initialised in 217ms [ 0.286012] devtmpfs: initialized [ 0.287243] x86/mm: Memory block size: 128MB [ 0.289938] gcov: version magic: 0x41383552 [ 0.291333] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.292105] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.293293] pinctrl core: initialized pinctrl subsystem [ 0.294167] [ 0.294607] ************************************************************* [ 0.295012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.296016] ** ** [ 0.297014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.298015] ** ** [ 0.299014] ** This means that this kernel is built to expose internal ** [ 0.300015] ** IOMMU data structures, which may compromise security on ** [ 0.301016] ** your system. ** [ 0.302016] ** ** [ 0.303016] ** If you see this message and you are not debugging the ** [ 0.304017] ** kernel, report this immediately to your vendor! ** [ 0.305018] ** ** [ 0.306014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.307020] ************************************************************* [ 0.308777] NET: Registered protocol family 16 [ 0.309474] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.310062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.311063] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.312481] cpuidle: using governor menu [ 0.314933] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.318738] PCI: Using configuration type 1 for base access [ 0.320164] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.330010] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.331026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.333070] cryptd: max_cpu_qlen set to 1000 [ 0.334295] ACPI: Added _OSI(Module Device) [ 0.336044] ACPI: Added _OSI(Processor Device) [ 0.337022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.339018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.343485] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.350337] ACPI: Interpreter enabled [ 0.351091] ACPI: PM: (supports S0 S3 S4 S5) [ 0.353019] ACPI: Using IOAPIC for interrupt routing [ 0.355191] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.359450] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.368758] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.371067] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.374027] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.377130] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.383295] acpiphp: Slot [2] registered [ 0.384223] acpiphp: Slot [5] registered [ 0.386168] acpiphp: Slot [6] registered [ 0.387159] acpiphp: Slot [3] registered [ 0.389128] acpiphp: Slot [4] registered [ 0.390117] acpiphp: Slot [7] registered [ 0.392184] acpiphp: Slot [8] registered [ 0.393143] acpiphp: Slot [9] registered [ 0.395151] acpiphp: Slot [10] registered [ 0.397180] acpiphp: Slot [11] registered [ 0.398125] acpiphp: Slot [12] registered [ 0.400149] acpiphp: Slot [13] registered [ 0.401135] acpiphp: Slot [14] registered [ 0.403151] acpiphp: Slot [15] registered [ 0.404139] acpiphp: Slot [16] registered [ 0.406144] acpiphp: Slot [17] registered [ 0.408150] acpiphp: Slot [18] registered [ 0.409128] acpiphp: Slot [19] registered [ 0.411145] acpiphp: Slot [20] registered [ 0.412189] acpiphp: Slot [21] registered [ 0.414167] acpiphp: Slot [22] registered [ 0.415141] acpiphp: Slot [23] registered [ 0.417153] acpiphp: Slot [24] registered [ 0.418128] acpiphp: Slot [25] registered [ 0.419119] acpiphp: Slot [26] registered [ 0.421181] acpiphp: Slot [27] registered [ 0.423131] acpiphp: Slot [28] registered [ 0.424141] acpiphp: Slot [29] registered [ 0.426133] acpiphp: Slot [30] registered [ 0.427124] acpiphp: Slot [31] registered [ 0.429126] PCI host bridge to bus 0000:00 [ 0.430026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.433042] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.435038] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.438044] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.440033] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.443076] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.445239] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.449072] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.452374] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.460022] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.464020] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.468031] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.471029] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.473028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.476595] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.479837] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.482048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.484734] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.490015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.500013] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.503013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.508594] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.514037] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.520028] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.533024] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.542587] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.549024] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.554030] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.568027] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.578724] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.581507] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.583425] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.586449] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.588324] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.593189] iommu: Default domain type: Passthrough [ 0.595528] SCSI subsystem initialized [ 0.597156] ACPI: bus type USB registered [ 0.598130] usbcore: registered new interface driver usbfs [ 0.600099] usbcore: registered new interface driver hub [ 0.602089] usbcore: registered new device driver usb [ 0.604206] pps_core: LinuxPPS API ver. 1 registered [ 0.606030] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.609064] PTP clock support registered [ 0.611085] EDAC MC: Ver: 3.0.0 [ 0.613218] PCI: Using ACPI for IRQ routing [ 0.614943] NetLabel: Initializing [ 0.617017] NetLabel: domain hash size = 128 [ 0.618013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.620120] NetLabel: unlabeled traffic allowed by default [ 0.623125] vgaarb: loaded [ 0.624317] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.627019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.632427] clocksource: Switched to clocksource kvm-clock [ 0.735964] VFS: Disk quotas dquot_6.6.0 [ 0.737622] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.740047] *** VALIDATE ramfs *** [ 0.741353] *** VALIDATE hugetlbfs *** [ 0.742832] pnp: PnP ACPI init [ 0.744996] pnp: PnP ACPI: found 6 devices [ 0.765704] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.769331] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.771820] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.773840] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.776395] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.778889] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.781947] NET: Registered protocol family 2 [ 0.784837] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.790446] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.795163] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.800845] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.804819] TCP: Hash tables configured (established 65536 bind 65536) [ 0.808038] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.811162] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.813527] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.816398] NET: Registered protocol family 1 [ 0.818980] RPC: Registered named UNIX socket transport module. [ 0.821317] RPC: Registered udp transport module. [ 0.825045] RPC: Registered tcp transport module. [ 0.826887] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.829681] NET: Registered protocol family 44 [ 0.831768] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.834087] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.836551] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.838425] PCI: CLS 0 bytes, default 64 [ 0.840255] Unpacking initramfs... [ 2.277097] debug: unmapping init [mem 0xffff892c3cc64000-0xffff892c3ffcffff] [ 2.280820] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.282915] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.285089] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.899267] Initialise system trusted keyrings [ 2.901013] Key type blacklist registered [ 2.903382] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.912813] zbud: loaded [ 2.917764] *** VALIDATE nfs *** [ 2.919099] *** VALIDATE nfs4 *** [ 2.920986] pstore: using deflate compression [ 2.924771] Platform Keyring initialized [ 3.105250] NET: Registered protocol family 38 [ 3.107125] Key type asymmetric registered [ 3.110385] Asymmetric key parser 'x509' registered [ 3.115590] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.126782] io scheduler mq-deadline registered [ 3.134447] io scheduler kyber registered [ 3.140242] io scheduler bfq registered [ 3.145139] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.156531] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.160109] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.164985] ACPI: Power Button [PWRF] [ 3.310936] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.441783] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.545653] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.575636] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.614597] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.624278] Non-volatile memory driver v1.3 [ 3.626217] Linux agpgart interface v0.103 [ 3.665224] virtio_blk virtio1: [vda] 68000 512-byte logical blocks (34.8 MB/33.2 MiB) [ 3.669474] vda: detected capacity change from 0 to 34816000 [ 3.688159] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.691639] vdb: detected capacity change from 0 to 1073741824 [ 3.703856] libphy: Fixed MDIO Bus: probed [ 3.713711] usbcore: registered new interface driver usbserial_generic [ 3.717717] usbserial: USB Serial support registered for generic [ 3.719869] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.726106] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.730565] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.735758] mousedev: PS/2 mouse device common for all mice [ 3.740950] rtc_cmos 00:05: RTC can wake from S4 [ 3.750332] rtc_cmos 00:05: registered as rtc0 [ 3.757890] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.764800] intel_pstate: CPU model not supported [ 3.769973] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.774958] hid: raw HID events driver (C) Jiri Kosina [ 3.784482] usbcore: registered new interface driver usbhid [ 3.786813] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.788566] usbhid: USB HID core driver [ 3.788925] drop_monitor: Initializing network drop monitor service [ 3.789075] Initializing XFRM netlink socket [ 3.789453] NET: Registered protocol family 10 [ 3.792551] Segment Routing with IPv6 [ 3.801109] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.802305] NET: Registered protocol family 17 [ 3.806425] mpls_gso: MPLS GSO support [ 3.823519] RAS: Correctable Errors collector initialized. [ 3.826100] AVX version of gcm_enc/dec engaged. [ 3.827706] AES CTR mode by8 optimization enabled [ 3.956468] sched_clock: Marking stable (3956439319, 0)->(4883604438, -927165119) [ 3.960458] registered taskstats version 1 [ 3.963270] Loading compiled-in X.509 certificates [ 3.967491] zswap: loaded using pool lzo/zbud [ 4.002230] Key type big_key registered [ 4.016344] Key type encrypted registered [ 4.018157] ima: No TPM chip found, activating TPM-bypass! [ 4.021289] ima: Allocated hash algorithm: sha1 [ 4.023360] ima: No architecture policies found [ 4.025626] evm: Initialising EVM extended attributes: [ 4.028008] evm: security.selinux [ 4.029537] evm: security.ima [ 4.030671] evm: security.capability [ 4.032461] evm: HMAC attrs: 0x1 [ 4.036477] rtc_cmos 00:05: setting system clock to 2025-12-05 12:12:13 UTC (1764936733) [ 4.042849] debug: unmapping init [mem 0xffffffffa8a03000-0xffffffffa8bfffff] [ 4.045786] debug: unmapping init [mem 0xffffffffa7782000-0xffffffffa7a58fff] [ 4.057100] Write protecting the kernel read-only data: 28672k [ 4.060997] debug: unmapping init [mem 0xffffffffa5e03000-0xffffffffa5ffffff] [ 4.063804] debug: unmapping init [mem 0xffffffffa6714000-0xffffffffa67fffff] [ 4.111295] 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) [ 4.125163] systemd[1]: Detected virtualization kvm. [ 4.127515] systemd[1]: Detected architecture x86-64. [ 4.129527] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.157797] systemd[1]: No hostname configured. [ 4.160737] systemd[1]: Set hostname to . [ 4.162846] random: systemd: uninitialized urandom read (16 bytes read) [ 4.165340] systemd[1]: Initializing machine ID from random generator. [ 4.203691] random: ln: uninitialized urandom read (6 bytes read) [ 4.384379] random: systemd: uninitialized urandom read (16 bytes read) [ 4.386898] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.397129] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 4.412713] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Slices. 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... [ 5.463846] device-mapper: uevent: version 1.0.3 [ 5.466690] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [[ 6.610516] random: fast init done  OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 6.726219] virtio_net virtio0 ens2: renamed from eth0 [ 6.953066] scsi host0: ata_piix [ 7.008409] scsi host1: ata_piix [ 7.010194] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.013285] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.545860] random: crng init done [ 11.547139] random: 7 urandom warning(s) missed due to ratelimiting [ 11.676786] dracut-initqueue[582]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 13.992463] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ 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 Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 16.797518] printk: systemd: 25 output lines suppressed due to ratelimiting [ 17.440588] SELinux: Disabled at runtime. [ 17.540272] 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) [ 17.563875] systemd[1]: Detected virtualization kvm. [ 17.565668] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.076514] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.088814] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.099767] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.107260] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.110580] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.138812] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.152363] systemd[1]: Listening on initctl Compatibility Named Pipe. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Switch Root. [ OK ] Reached target Paths. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Mounting Huge Pages File System... [ OK ] Reached target RPC Port Mapper. [ OK ] Reached target rpc_pipefs.target. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd Root File System. Starting udev Coldplug all Devices... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Create list of required st…ce nodes f[ 19.540507] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS or the current kernel... Mounting POSIX Message Queue File System... Mounting Kernel Debug File System... [ 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 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 ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 20.174940] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 20.843400] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 20.861845] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.378280] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 21.639321] EDAC sbridge: Ver: 1.1.2 [ 23.632842] Key type dns_resolver registered [ 24.067895] NFS: Registering the id_resolver key type [ 24.069415] Key type id_resolver registered [ 24.070982] Key type id_legacy registered [ 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 RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Started irqbalance daemon. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg103-client login: [ 69.964867] libcfs: loading out-of-tree module taints kernel. [ 70.065656] alg: No test for adler32 (adler32-zlib) [ 70.835508] Key type ._llcrypt registered [ 70.839472] Key type .llcrypt registered [ 71.151433] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.709399] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 72.266451] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 72.272249] LNet: Accept secure, port 988 [ 74.008368] Key type lgssc registered [ 75.042527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 169.868066] hrtimer: interrupt took 2942880 ns [ 202.532274] Lustre: Mounted lustre-client [ 206.881658] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 220.074106] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_logdir /tmp/testlogs/ [ 223.770367] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing yml_node [ 227.224816] Lustre: DEBUG MARKER: Client: 2.15.8.1 [ 228.321240] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 23s idle [ 228.918750] Lustre: DEBUG MARKER: MDS: 2.15.8.1 [ 230.728974] Lustre: DEBUG MARKER: OSS: 2.15.8.1 [ 231.993677] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Dec 5 07:16:00 EST 2025 [ 237.249201] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 238.193916] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 238.760561] Lustre: Mounted lustre-client [ 243.804366] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing check_config_client /mnt/lustre [ 260.085155] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 272.178187] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 07:16:40 (1764937000) [ 278.974228] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 07:16:47 (1764937007) [ 284.395864] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 07:16:52 (1764937012) [ 290.202267] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 07:16:58 (1764937018) [ 296.014777] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 07:17:04 (1764937024) [ 300.956510] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 07:17:09 (1764937029) [ 306.596781] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 07:17:14 (1764937034) [ 313.891871] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 07:17:21 (1764937041) [ 321.379048] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 07:17:29 (1764937049) [ 329.047960] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 07:17:37 (1764937057) [ 336.158250] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 07:17:44 (1764937064) [ 336.356295] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 22s idle [ 336.359418] Lustre: Skipped 1 previous similar message [ 342.581324] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 07:17:50 (1764937070) [ 348.621089] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 07:17:56 (1764937076) [ 351.714288] Lustre: lustre-OST0001-osc-ffff892c98d59000: disconnect after 22s idle [ 354.831528] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 07:18:03 (1764937083) [ 362.007828] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 07:18:10 (1764937090) [ 369.270455] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 07:18:17 (1764937097) [ 375.493491] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 07:18:23 (1764937103) [ 382.283479] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 07:18:30 (1764937110) [ 387.533937] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 07:18:35 (1764937115) [ 393.230949] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 07:18:41 (1764937121) [ 393.724497] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279508 file: /mnt/lustre/lockdir/lockfile=144115205289279507 [ 539.648124] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 07:21:08 (1764937268) [ 545.990705] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 07:21:14 (1764937274) [ 551.719312] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 07:21:20 (1764937280) [ 557.521860] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 07:21:25 (1764937285) [ 563.766164] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 07:21:32 (1764937292) [ 570.482599] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 07:21:38 (1764937298) [ 572.248604] Lustre: DEBUG MARKER: chmod [ 578.028594] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 07:21:46 (1764937306) [ 584.939989] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207340kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 593.513748] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 07:22:01 (1764937321) [ 848.819144] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 07:26:17 (1764937577) [ 967.330394] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 07:28:15 (1764937695) [ 1138.390071] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 07:31:06 (1764937866) [ 1170.483199] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 07:31:38 (1764937898) [ 1170.912270] Lustre: lustre-OST0001-osc-ffff892c98d59000: disconnect after 20s idle [ 1176.367603] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 07:31:44 (1764937904) [ 1177.324184] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.415801] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.499328] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.598209] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.685657] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.788096] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.852942] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.908315] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1177.982833] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.062263] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.138221] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.212807] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.288329] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.358886] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.408623] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.467891] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.589677] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.657844] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.715532] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.795738] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.848392] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1178.939577] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.064270] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.136223] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.218920] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.300454] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.364920] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.476169] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.575138] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.631969] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.720641] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.790353] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.866224] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1179.962145] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.029785] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.093408] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.180085] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.250475] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.333934] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.423132] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.509633] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.572930] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.644719] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.716604] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.800108] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.902107] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1180.988581] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.111763] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.232268] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.289861] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.322566] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.412653] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.494793] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.561747] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.629837] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.698864] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.763574] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.844924] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.929245] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1181.999175] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.074343] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.179452] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.235819] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.381248] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.497420] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.566175] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.617280] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.689214] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.770223] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.846125] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.898096] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1182.967112] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.032972] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.093823] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.153665] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.237871] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.302091] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.378732] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.428026] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.469197] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.513589] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.592993] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.680617] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.727928] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.786628] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.859199] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1183.956527] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.013971] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.067150] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.137241] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.202337] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.309136] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.404765] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.458245] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.543374] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.613406] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.661313] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.768231] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.831121] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.886578] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.931597] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1184.996691] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.075759] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.140771] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.215969] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.282799] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.332210] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.417913] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.475255] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.558333] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.640698] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.710156] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.816398] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.874399] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1185.935788] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.031699] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.104111] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.171749] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.287619] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.350142] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.403439] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.477408] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.549479] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.606742] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.708804] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.827867] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1186.927375] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.035540] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.121118] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.208569] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.279240] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.338479] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.394684] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.472440] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.549720] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.642490] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.703150] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.744606] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.810412] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.893050] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1187.957934] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.048528] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.123968] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.185370] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.280093] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.349275] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.414318] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.469160] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.538701] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.585935] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.635216] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.684842] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.748878] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.821792] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.863786] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.920558] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1188.977715] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.028521] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.092516] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.148812] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.203710] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.245470] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.312655] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.372509] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.471243] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.546574] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.634732] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.709122] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.773271] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.822968] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.905885] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1189.965377] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.061406] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.127698] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.208554] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.288242] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.352727] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.424543] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.480440] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.536659] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.607950] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.658469] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.715785] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.778324] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.839311] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.895247] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1190.965327] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.035366] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.100425] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.169502] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.221857] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.276882] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.332889] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.393875] Lustre: lustre-OST0000-osc-ffff892c98d59000: disconnect after 20s idle [ 1191.409042] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.464675] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.552150] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.624408] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.686366] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.768867] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.840270] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.919817] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1191.994283] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.080118] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.180960] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.278883] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.341282] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.393718] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.527443] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.590103] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.648595] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.722992] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.797441] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.872800] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1192.952474] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.041281] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.085211] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.150890] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.207666] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.301861] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.377390] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.427827] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.497033] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.545762] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.605653] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.647346] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.693455] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.769726] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.851731] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1193.906606] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.004248] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.052844] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.118887] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.185974] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.252341] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.303154] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.363335] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.422828] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.483437] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.544777] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.608102] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.655493] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.710427] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.783727] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.843478] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.891554] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1194.947100] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.001601] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.039281] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.087105] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.141037] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.194250] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.238280] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.315109] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.359228] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.399103] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.492268] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.567558] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.622722] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.705792] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.777158] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.860351] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.928269] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1195.986499] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.044374] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.105676] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.179218] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.235598] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.290451] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.347681] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.411664] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.465455] rw_seq_cst_vs_d (29815): drop_caches: 3 [ 1196.515965] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 20s idle [ 1202.991725] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 07:32:11 (1764937931) [ 1203.341072] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.433376] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.537489] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.588901] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.699384] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.808061] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.888812] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1203.926433] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.063275] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.108750] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.324168] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.441619] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.490857] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.568842] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.701304] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.751141] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1204.923424] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.050318] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.143778] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.270236] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.313522] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.496607] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.678602] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.911522] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1205.961495] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.046450] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.091205] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.283392] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.367061] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.518361] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.666531] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.864879] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1206.926430] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.069145] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.257821] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.309728] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.393985] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.538903] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.622741] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.765676] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.808246] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.838141] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.918930] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1207.964231] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.100802] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.149074] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.222519] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.287404] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.360678] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.525069] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.574882] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.629322] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.843496] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.888475] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.936827] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1208.974921] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.028237] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.071335] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.155949] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.276815] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.415350] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.496392] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.537383] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.592875] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.737635] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.822835] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.860800] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1209.971200] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.044144] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.216989] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.277354] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.374263] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.521411] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.607886] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.653456] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.723805] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.831685] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1210.923792] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.077710] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.144039] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.269312] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.422143] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.481676] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.603738] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.707380] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.792780] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.819518] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.849459] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1211.986940] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.014570] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.041749] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.183485] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.266427] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.292922] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.335329] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.450262] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.584749] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.676354] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.758196] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.798253] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.953419] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1212.989294] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.083759] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.193966] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.288285] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.432344] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.621778] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.645117] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.704463] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1213.725867] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.082843] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.170990] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.253606] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.356116] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.459145] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.499325] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.657540] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.763748] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.832457] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1214.917377] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.012043] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.076478] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.253330] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.285902] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.310694] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.379384] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.447848] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.540640] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.574042] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.603991] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.746812] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1215.894144] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.003819] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.125245] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.161878] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.327953] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.417202] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.548930] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.618152] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.699099] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.876974] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.981533] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1216.992300] Lustre: lustre-OST0001-osc-ffff892c98d59000: disconnect after 21s idle [ 1217.034885] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.060899] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.125131] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.167392] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.301894] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.336763] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.542300] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.630178] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.742486] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.787226] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.815593] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.919645] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1217.966906] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.098857] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.131128] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.253108] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.290835] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.386276] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.490076] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.524110] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.644697] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.696021] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.775473] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.824405] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.874477] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.928579] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1218.982025] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.108687] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.214064] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.260248] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.343542] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.480668] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.630434] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.682805] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.730631] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.800816] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.910030] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1219.952842] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.101307] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.173768] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.225524] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.456652] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.515186] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.573668] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.651465] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.692474] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.740729] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.866385] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.949125] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1220.987961] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.028220] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.119210] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.194463] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.316285] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.463669] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.505857] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.547695] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.639228] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.762476] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.833766] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.884109] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1221.974290] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.030142] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.114283] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 20s idle [ 1222.211577] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.391915] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.503191] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.547970] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.599556] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.638611] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.785401] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.866387] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1222.960832] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1223.004227] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1223.093661] rw_seq_cst_vs_d (30399): drop_caches: 3 [ 1230.243179] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 07:32:38 (1764937958) [ 1238.442968] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 07:32:46 (1764937966) [ 1248.613877] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 07:32:56 (1764937976) [ 1284.758949] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 07:33:32 (1764938012) [ 1293.927669] Lustre: DEBUG MARKER: loop 5 [ 1298.807512] Lustre: DEBUG MARKER: loop 10 [ 1298.916104] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 22s idle [ 1303.301900] Lustre: DEBUG MARKER: loop 15 [ 1307.614223] Lustre: DEBUG MARKER: loop 20 [ 1314.067647] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 07:34:02 (1764938042) [ 1319.973332] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 07:34:08 (1764938048) [ 1326.411987] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 07:34:14 (1764938054) [ 1334.753702] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 20s idle [ 1334.759447] Lustre: Skipped 1 previous similar message [ 1396.307527] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 07:35:24 (1764938124) [ 1403.341975] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 07:35:31 (1764938131) [ 1408.936070] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 07:35:37 (1764938137) [ 1415.370869] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 07:35:43 (1764938143) [ 1422.836432] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 07:35:50 (1764938150) [ 1429.565463] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 07:35:57 (1764938157) [ 1437.159820] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 22s idle [ 1437.165247] Lustre: Skipped 3 previous similar messages [ 1437.234535] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1438.451288] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 1439.874678] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 07:36:08 (1764938168) [ 1447.156635] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 07:36:15 (1764938175) [ 1447.618260] Lustre: *** cfs_fail_loc=314, val=0*** [ 1448.672240] Lustre: *** cfs_fail_loc=314, val=0*** [ 1448.674260] Lustre: Skipped 2 previous similar messages [ 1454.574926] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 07:36:22 (1764938182) [ 1463.224914] Lustre: *** cfs_fail_loc=314, val=0*** [ 1463.296753] LustreError: 11-0: lustre-OST0000-osc-ffff892c98d59000: operation ldlm_enqueue to node 192.168.201.103@tcp failed: rc = -107 [ 1463.313443] Lustre: lustre-OST0000-osc-ffff892c98d59000: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1463.356735] LustreError: lustre-OST0000-osc-ffff892c98d59000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1463.371687] Lustre: 2261:0:(llite_lib.c:3595:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.103@tcp:/lustre/fid: [0x240000403:0x1:0x0]// may get corrupted (rc -108) [ 1463.384170] LustreError: 41220:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff892c98d59000: namespace resource [0x23:0x0:0x0].0x0 (00000000a4c0a969) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1463.406043] Lustre: lustre-OST0000-osc-ffff892c98d59000: Connection restored to (at 192.168.201.103@tcp) [ 1469.411791] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 07:36:37 (1764938197) [ 1469.725208] LustreError: 41807:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1472.760170] LustreError: 41807:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 1478.158897] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1479.935121] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 07:36:47 (1764938207) [ 1481.363809] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1482.582653] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 07:36:51 (1764938211) [ 1488.358688] Lustre: lustre-MDT0000-mdc-ffff892c84c89800: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1493.483790] LustreError: 166-1: MGC192.168.201.103@tcp: Connection to MGS (at 192.168.201.103@tcp) was lost; in progress operations using this service will fail [ 1493.500610] Lustre: Evicted from MGS (at 192.168.201.103@tcp) after server handle changed from 0x92f57160bd549803 to 0x92f57160bd5fa8da [ 1493.512458] Lustre: MGC192.168.201.103@tcp: Connection restored to (at 192.168.201.103@tcp) [ 1495.846951] Lustre: lustre-MDT0000-mdc-ffff892c98d59000: Connection restored to (at 192.168.201.103@tcp) [ 1513.918270] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 07:37:22 (1764938242) [ 1611.242103] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 21s idle [ 1611.244676] Lustre: Skipped 3 previous similar messages [ 1611.534783] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 07:38:59 (1764938339) [ 1613.166772] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 1615.174669] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 07:39:03 (1764938343) [ 1667.561101] Lustre: lustre-OST0000-osc-ffff892c84c89800: Connection to lustre-OST0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1667.580626] Lustre: Skipped 1 previous similar message [ 1667.605108] LustreError: lustre-OST0000-osc-ffff892c84c89800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1667.614232] LustreError: lustre-OST0000-osc-ffff892c98d59000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1667.615368] Lustre: lustre-OST0000-osc-ffff892c84c89800: Connection restored to (at 192.168.201.103@tcp) [ 1667.631046] Lustre: Skipped 1 previous similar message [ 1671.629065] Lustre: lustre-OST0001-osc-ffff892c84c89800: Connection to lustre-OST0001 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1671.645286] Lustre: Skipped 1 previous similar message [ 1671.661811] LustreError: lustre-OST0001-osc-ffff892c84c89800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1671.677205] Lustre: lustre-OST0001-osc-ffff892c84c89800: Connection restored to (at 192.168.201.103@tcp) [ 1671.683152] Lustre: Skipped 1 previous similar message [ 1689.055627] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid 40 [ 1690.968495] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1692.299551] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid in IDLE state after 0 sec [ 1697.707200] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid 40 [ 1699.094213] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1700.377957] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid in FULL state after 0 sec [ 1705.871200] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid 40 [ 1706.984946] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1708.126531] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid in IDLE state after 0 sec [ 1713.430912] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid 40 [ 1714.695533] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1716.086280] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid in FULL state after 0 sec [ 1728.071743] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid 40 [ 1729.839912] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1731.860955] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff892c98d59000.ost_server_uuid in IDLE state after 0 sec [ 1739.463722] Lustre: DEBUG MARKER: oleg103-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid,osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid 40 [ 1741.458759] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c84c89800.ost_server_uuid in IDLE state after 0 sec [ 1743.025621] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff892c98d59000.ost_server_uuid in FULL state after 0 sec [ 1744.935605] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 07:41:12 (1764938472) [ 1747.427834] Lustre: DEBUG MARKER: Race attempt 0 [ 1750.337465] Lustre: DEBUG MARKER: Wait for 54325 54344 for 60 sec... [ 1815.753436] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 07:42:24 (1764938544) [ 1824.054338] Lustre: DEBUG MARKER: start test - cycle (0) [ 1846.071059] Lustre: DEBUG MARKER: start test - cycle (1) [ 1865.425193] Lustre: DEBUG MARKER: start test - cycle (2) [ 1886.173922] Lustre: DEBUG MARKER: start test - cycle (3) [ 1907.217277] Lustre: DEBUG MARKER: start test - cycle (4) [ 1928.518577] Lustre: DEBUG MARKER: start test - cycle (5) [ 1953.704673] Lustre: DEBUG MARKER: start test - cycle (6) [ 1972.939444] Lustre: DEBUG MARKER: start test - cycle (7) [ 1993.623387] Lustre: DEBUG MARKER: start test - cycle (8) [ 2015.831193] Lustre: DEBUG MARKER: start test - cycle (9) [ 2036.435855] Lustre: DEBUG MARKER: start test - cycle (10) [ 2060.311171] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 07:46:28 (1764938788) [ 2066.913340] Lustre: lustre-OST0000-osc-ffff892c98d59000: disconnect after 21s idle [ 2066.916056] Lustre: Skipped 3 previous similar messages [ 2143.629963] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 07:47:51 (1764938871) [ 2150.754382] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 07:47:58 (1764938878) [ 2158.743117] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 07:48:06 (1764938886) [ 2165.542827] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 07:48:13 (1764938893) [ 2165.896992] Lustre: *** cfs_fail_loc=411, val=0*** [ 2171.804308] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 07:48:20 (1764938900) [ 2186.743361] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 07:48:34 (1764938914) [ 2203.157895] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 07:48:51 (1764938931) [ 2218.380951] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 07:49:06 (1764938946) [ 2233.705351] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 07:49:21 (1764938961) [ 2247.795950] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 07:49:36 (1764938976) [ 2256.879958] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 07:49:45 (1764938985) [ 2267.577641] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 07:49:56 (1764938996) [ 2277.669361] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 07:50:05 (1764939005) [ 2289.594185] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 07:50:17 (1764939017) [ 2301.938736] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 07:50:29 (1764939029) [ 2313.840661] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 07:50:41 (1764939041) [ 2326.095602] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 07:50:54 (1764939054) [ 2333.154421] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 23s idle [ 2333.158627] Lustre: Skipped 10 previous similar messages [ 2338.532928] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 07:51:06 (1764939066) [ 3319.727903] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 08:07:28 (1764940048) [ 3332.028381] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 08:07:40 (1764940060) [ 3344.172547] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 08:07:52 (1764940072) [ 3356.444561] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 08:08:04 (1764940084) [ 3367.854123] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 08:08:15 (1764940095) [ 3377.634903] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 23s idle [ 3377.637059] Lustre: Skipped 2 previous similar messages [ 3378.795590] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 08:08:27 (1764940107) [ 3389.945484] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 08:08:38 (1764940118) [ 3400.813710] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 08:08:49 (1764940129) [ 3410.920894] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 08:08:59 (1764940139) [ 3483.611875] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 08:10:12 (1764940212) [ 3494.533916] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 08:10:22 (1764940222) [ 3506.097856] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 08:10:34 (1764940234) [ 3516.781469] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 08:10:45 (1764940245) [ 3526.957924] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 08:10:55 (1764940255) [ 3537.207939] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 08:11:05 (1764940265) [ 3546.877316] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 08:11:15 (1764940275) [ 3556.640360] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 08:11:25 (1764940285) [ 3566.081126] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 08:11:34 (1764940294) [ 3664.731813] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 08:13:13 (1764940393) [ 4409.425445] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 08:25:38 (1764941138) [ 4416.069779] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 08:25:44 (1764941144) [ 4422.279157] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 08:25:51 (1764941151) [ 4428.349262] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 08:25:57 (1764941157) [ 4434.727567] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 08:26:03 (1764941163) [ 4441.347262] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 08:26:10 (1764941170) [ 4447.518640] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 08:26:16 (1764941176) [ 4453.829217] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 08:26:22 (1764941182) [ 4461.141988] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 08:26:29 (1764941189) [ 4468.035809] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 08:26:36 (1764941196) [ 4483.552707] Lustre: lustre-OST0000-osc-ffff892c84c89800: disconnect after 25s idle [ 4483.557984] Lustre: Skipped 6 previous similar messages [ 4532.062570] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 08:27:40 (1764941260) [ 4539.266401] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 08:27:48 (1764941268) [ 4546.802152] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 08:27:55 (1764941275) [ 4553.652242] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 08:28:02 (1764941282) [ 4559.928833] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 08:28:08 (1764941288) [ 4566.011761] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 08:28:14 (1764941294) [ 4572.381862] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 08:28:21 (1764941301) [ 4578.069213] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 08:28:26 (1764941306) [ 4584.303477] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 08:28:33 (1764941313) [ 5190.112524] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 20s idle [ 5190.115560] Lustre: Skipped 3 previous similar messages [ 5264.705472] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 08:39:53 (1764941993) [ 5273.327704] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 08:40:01 (1764942001) [ 5282.502710] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 08:40:11 (1764942011) [ 5292.091317] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 08:40:20 (1764942020) [ 5300.893439] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 08:40:29 (1764942029) [ 5309.264735] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 08:40:37 (1764942037) [ 5318.078849] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 08:40:46 (1764942046) [ 5326.449662] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 08:40:55 (1764942055) [ 5334.722233] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 08:41:03 (1764942063) [ 5342.923912] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 08:41:11 (1764942071) [ 5350.972662] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 08:41:19 (1764942079) [ 5360.160816] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 08:41:28 (1764942088) [ 5368.075749] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 08:41:36 (1764942096) [ 5375.726234] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 08:41:44 (1764942104) [ 5383.365645] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 08:41:52 (1764942112) [ 5390.619111] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 08:41:59 (1764942119) [ 5399.180321] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 08:42:07 (1764942127) [ 5399.311057] LustreError: 5564:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5401.392114] LustreError: 5564:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 5407.478507] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 08:42:16 (1764942136) [ 5412.532511] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 08:42:21 (1764942141) [ 5412.703308] LustreError: 267371:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5416.768133] LustreError: 267371:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5416.791405] LustreError: 267371:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5420.856357] LustreError: 267371:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5420.881542] LustreError: 267377:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5424.944394] LustreError: 267377:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 5428.496654] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 08:42:37 (1764942157) [ 5436.734225] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 08:42:45 (1764942165) [ 5441.194678] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 08:42:49 (1764942169) [ 5446.463437] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 08:42:55 (1764942175) [ 5473.142340] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 08:43:21 (1764942201) [ 5482.177985] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 08:43:30 (1764942210) [ 5491.255591] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 08:43:40 (1764942220) [ 5505.464520] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 08:43:54 (1764942234) [ 5517.141210] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 08:44:05 (1764942245) [ 5520.839977] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5523.965526] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 08:44:12 (1764942252) [ 5527.857720] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 08:44:16 (1764942256) [ 5531.779872] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 08:44:20 (1764942260) [ 5535.855224] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 08:44:24 (1764942264) [ 5552.485832] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 08:44:41 (1764942281) [ 5570.542184] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 08:44:59 (1764942299) [ 5573.997701] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 08:45:02 (1764942302) [ 5577.103888] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 08:45:05 (1764942305) [ 5580.244040] LustreError: 11-0: lustre-MDT0000-mdc-ffff892c98d59000: operation ldlm_enqueue to node 192.168.201.103@tcp failed: rc = -35 [ 5583.980870] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 08:45:12 (1764942312) [ 5584.253724] LustreError: 2259:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5586.328159] LustreError: 2259:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 5592.172828] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 08:45:20 (1764942320) [ 5666.184270] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 08:46:34 (1764942394) [ 5670.872087] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 08:46:39 (1764942399) [ 5677.240689] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 08:46:46 (1764942406) [ 5686.081455] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 08:46:54 (1764942414) [ 5693.302792] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 08:47:02 (1764942422) [ 5704.858396] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 08:47:13 (1764942433) [ 5715.751457] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 08:47:24 (1764942444) [ 5721.508489] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 08:47:30 (1764942450) [ 5727.845347] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 08:47:36 (1764942456) [ 5737.957365] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 08:47:46 (1764942466) [ 5783.196453] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 08:48:32 (1764942512) [ 5804.512263] Lustre: lustre-OST0001-osc-ffff892c84c89800: disconnect after 20s idle [ 5804.515626] Lustre: Skipped 11 previous similar messages [ 5898.027504] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 08:50:26 (1764942626) [ 6189.601609] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 08:55:18 (1764942918) [ 6193.908684] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 08:55:22 (1764942922) [ 6245.329540] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 08:56:14 (1764942974) [ 6291.218216] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 08:57:00 (1764943020) [ 6296.090634] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 08:57:04 (1764943024) [ 6341.628766] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 08:57:50 (1764943070) [ 6355.432683] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 08:58:04 (1764943084) [ 6359.257855] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 08:58:08 (1764943088) [ 6372.340748] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 08:58:21 (1764943101) [ 6373.578808] LustreError: 307927:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x200000402:0x7b7:0x0] mdc close failed: rc = -116 [ 6374.086061] LustreError: 307930:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x240000402:0x603:0x0] mdc close failed: rc = -116 [ 6374.090167] LustreError: 307930:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 6376.945274] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 08:58:25 (1764943105) [ 6380.606635] LustreError: 308652:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c98d59000: inode [0x200000402:0x7df:0x0] mdc close failed: rc = -2 [ 6380.613084] LustreError: 308652:0:(file.c:246:ll_close_inode_openhandle()) Skipped 9 previous similar messages [ 6382.939068] LustreError: 308730:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x240000402:0x65e:0x0] mdc close failed: rc = -116 [ 6382.944719] LustreError: 308730:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 6391.459211] LustreError: 309079:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x240000402:0x6b4:0x0] mdc close failed: rc = -116 [ 6400.789364] LustreError: 309488:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x240000402:0x71a:0x0] mdc close failed: rc = -116 [ 6400.794804] LustreError: 309488:0:(file.c:246:ll_close_inode_openhandle()) Skipped 5 previous similar messages [ 6417.049201] LustreError: 310201:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff892c84c89800: inode [0x200000402:0xa5b:0x0] mdc close failed: rc = -116 [ 6417.055323] LustreError: 310201:0:(file.c:246:ll_close_inode_openhandle()) Skipped 10 previous similar messages [ 6439.193246] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 08:59:28 (1764943168) [ 6441.853372] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 08:59:30 (1764943170) [ 6459.872264] Lustre: lustre-OST0000-osc-ffff892c98d59000: disconnect after 23s idle [ 6459.876299] Lustre: Skipped 10 previous similar messages [ 6473.114367] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 09:00:01 (1764943201) [ 6473.737128] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6474.479991] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 09:00:03 (1764943203) [ 6477.068543] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 09:00:05 (1764943205) [ 6600.039734] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 09:02:08 (1764943328) [ 6607.891733] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 09:02:16 (1764943336) [ 6790.646306] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 09:05:19 (1764943519) [ 6973.129612] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 09:08:22 (1764943702) [ 6975.419658] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 09:08:24 (1764943704) [ 6984.271554] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 09:08:33 (1764943713) [ 6984.317140] Lustre: DEBUG MARKER: write [ 6984.334603] LustreError: 20959:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6986.339449] Lustre: DEBUG MARKER: kill 335248 [ 6986.340940] LustreError: 335248:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6989.432091] LustreError: 20959:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 6992.376186] LustreError: 335248:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 6994.499438] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 09:08:43 (1764943723) [ 6994.611095] LustreError: 335846:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6996.696115] LustreError: 335846:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 7002.658555] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 09:08:51 (1764943731) [ 7003.186022] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7003.752638] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 09:08:52 (1764943732) [ 7005.856956] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 09:08:54 (1764943734) [ 7007.978335] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 09:08:56 (1764943736) [ 7010.310382] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 09:08:59 (1764943739) [ 7012.742691] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 09:09:01 (1764943741) [ 7015.340981] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 09:09:04 (1764943744) [ 7017.891294] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 09:09:06 (1764943746) [ 7021.228496] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 09:09:10 (1764943750) [ 7024.098229] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 09:09:13 (1764943753) [ 7024.707154] Lustre: *** cfs_fail_loc=415, val=0*** [ 7031.277042] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 09:09:20 (1764943760) [ 7050.616922] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 09:09:39 (1764943779) [ 7050.727802] LustreError: 17994:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7055.824104] LustreError: 17994:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7060.928148] LustreError: 17994:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7060.931651] LustreError: 17994:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7060.935534] LustreError: 17994:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7071.128152] LustreError: 17994:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7071.130886] LustreError: 17994:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 7081.329153] LustreError: 5574:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7081.331867] LustreError: 5574:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7091.528136] LustreError: 5574:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7091.530255] LustreError: 5574:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 7117.018151] LustreError: 282251:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7117.020672] LustreError: 282251:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7127.216159] LustreError: 282251:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 7127.218131] LustreError: 282251:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 7134.780262] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 09:11:03 (1764943863) [ 7137.364756] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 09:11:06 (1764943866) [ 7140.178360] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 09:11:09 (1764943869) [ 7142.584516] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 09:11:11 (1764943871) [ 7146.998087] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 09:11:15 (1764943875) [ 7155.408688] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 09:11:24 (1764943884) [ 7161.819569] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 09:11:30 (1764943890) [ 7162.703379] Lustre: Unmounted lustre-client [ 7163.592720] Lustre: Unmounted lustre-client [ 7164.093947] Lustre: DEBUG MARKER: Iteration 1 [ 7164.246670] LustreError: 347348:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7164.247071] LustreError: 347347:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7164.252780] LustreError: 347348:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7164.290350] Lustre: Mounted lustre-client [ 7164.855534] Lustre: Unmounted lustre-client [ 7165.779180] Key type lgssc unregistered [ 7165.887540] LNet: 347694:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7166.944906] LNet: Removed LNI 192.168.201.3@tcp [ 7167.172510] Key type .llcrypt unregistered [ 7167.173612] Key type ._llcrypt unregistered [ 7167.581050] alg: No test for adler32 (adler32-zlib) [ 7168.340338] Key type ._llcrypt registered [ 7168.341193] Key type .llcrypt registered [ 7168.464252] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7168.703686] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7168.941417] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7168.943826] LNet: Accept secure, port 988 [ 7170.552115] Key type lgssc registered [ 7171.044044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7175.193580] Lustre: DEBUG MARKER: Iteration 2 [ 7175.299265] LustreError: 348488:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7175.299314] LustreError: 348489:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7175.304600] LustreError: 348488:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7176.354230] Lustre: Mounted lustre-client [ 7176.355634] Lustre: Skipped 1 previous similar message [ 7176.882440] Lustre: Unmounted lustre-client [ 7177.779548] Key type lgssc unregistered [ 7177.870568] LNet: 348838:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7178.912786] LNet: Removed LNI 192.168.201.3@tcp [ 7179.138954] Key type .llcrypt unregistered [ 7179.140075] Key type ._llcrypt unregistered [ 7179.387873] alg: No test for adler32 (adler32-zlib) [ 7180.139328] Key type ._llcrypt registered [ 7180.140354] Key type .llcrypt registered [ 7180.212647] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7180.312311] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7180.388453] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7180.390895] LNet: Accept secure, port 988 [ 7181.976113] Key type lgssc registered [ 7182.319437] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7185.929163] Lustre: DEBUG MARKER: Iteration 3 [ 7186.032088] LustreError: 349630:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7186.033203] LustreError: 349631:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7186.038995] LustreError: 349630:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7187.082034] Lustre: Mounted lustre-client [ 7187.083183] Lustre: Skipped 1 previous similar message [ 7187.714107] Lustre: Unmounted lustre-client [ 7187.715177] Lustre: Skipped 1 previous similar message [ 7188.666322] Key type lgssc unregistered [ 7188.768443] LNet: 349975:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7189.792710] LNet: Removed LNI 192.168.201.3@tcp [ 7190.016559] Key type .llcrypt unregistered [ 7190.017657] Key type ._llcrypt unregistered [ 7190.321835] alg: No test for adler32 (adler32-zlib) [ 7191.073403] Key type ._llcrypt registered [ 7191.074317] Key type .llcrypt registered [ 7191.138903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7191.234795] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7191.301732] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7191.303964] LNet: Accept secure, port 988 [ 7192.888132] Key type lgssc registered [ 7193.264727] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7197.046780] Lustre: DEBUG MARKER: Iteration 4 [ 7197.151889] LustreError: 350768:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7197.151948] LustreError: 350769:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7197.160133] LustreError: 350768:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7198.209036] Lustre: Mounted lustre-client [ 7198.777363] Lustre: Unmounted lustre-client [ 7199.761893] Key type lgssc unregistered [ 7199.859567] LNet: 351116:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7200.864449] LNet: Removed LNI 192.168.201.3@tcp [ 7201.127028] Key type .llcrypt unregistered [ 7201.128134] Key type ._llcrypt unregistered [ 7201.434195] alg: No test for adler32 (adler32-zlib) [ 7202.197369] Key type ._llcrypt registered [ 7202.198587] Key type .llcrypt registered [ 7202.283110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7202.397640] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7202.477216] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7202.479111] LNet: Accept secure, port 988 [ 7204.064085] Key type lgssc registered [ 7204.444180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7208.325634] Lustre: DEBUG MARKER: Iteration 5 [ 7208.438631] LustreError: 351909:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7208.439256] LustreError: 351910:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7208.446739] LustreError: 351909:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7209.496040] Lustre: Mounted lustre-client [ 7210.069695] Lustre: Unmounted lustre-client [ 7211.140275] Key type lgssc unregistered [ 7211.263451] LNet: 352257:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7212.320769] LNet: Removed LNI 192.168.201.3@tcp [ 7212.576705] Key type .llcrypt unregistered [ 7212.577850] Key type ._llcrypt unregistered [ 7212.954100] alg: No test for adler32 (adler32-zlib) [ 7213.706434] Key type ._llcrypt registered [ 7213.708116] Key type .llcrypt registered [ 7213.788475] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7213.909225] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7213.999600] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7214.001565] LNet: Accept secure, port 988 [ 7215.592108] Key type lgssc registered [ 7216.013335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7219.558563] Lustre: DEBUG MARKER: Iteration 6 [ 7219.666168] LustreError: 353050:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7219.666205] LustreError: 353049:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7219.674590] LustreError: 353050:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7220.712946] Lustre: Mounted lustre-client [ 7221.296159] Lustre: Unmounted lustre-client [ 7222.218234] Key type lgssc unregistered [ 7222.323513] LNet: 353396:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7223.328974] LNet: Removed LNI 192.168.201.3@tcp [ 7223.573268] Key type .llcrypt unregistered [ 7223.574974] Key type ._llcrypt unregistered [ 7223.846049] alg: No test for adler32 (adler32-zlib) [ 7224.597395] Key type ._llcrypt registered [ 7224.599806] Key type .llcrypt registered [ 7224.677164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7224.786663] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7224.867419] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7224.868790] LNet: Accept secure, port 988 [ 7226.456130] Key type lgssc registered [ 7226.823640] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7230.317215] Lustre: DEBUG MARKER: Iteration 7 [ 7230.423153] LustreError: 354188:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7230.423391] LustreError: 354189:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7230.428221] LustreError: 354188:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7231.476679] Lustre: Mounted lustre-client [ 7232.113157] Lustre: Unmounted lustre-client [ 7232.114386] Lustre: Skipped 1 previous similar message [ 7233.023790] Key type lgssc unregistered [ 7233.114423] LNet: 354539:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7234.144721] LNet: Removed LNI 192.168.201.3@tcp [ 7234.345152] Key type .llcrypt unregistered [ 7234.346572] Key type ._llcrypt unregistered [ 7234.620021] alg: No test for adler32 (adler32-zlib) [ 7235.371351] Key type ._llcrypt registered [ 7235.372305] Key type .llcrypt registered [ 7235.431718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7235.525918] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7235.597094] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7235.598686] LNet: Accept secure, port 988 [ 7237.168100] Key type lgssc registered [ 7237.502018] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7240.959549] Lustre: DEBUG MARKER: Iteration 8 [ 7241.057079] LustreError: 355333:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7241.057122] LustreError: 355332:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7241.061113] LustreError: 355333:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7242.106806] Lustre: Mounted lustre-client [ 7242.705926] Lustre: Unmounted lustre-client [ 7242.707800] Lustre: Skipped 1 previous similar message [ 7243.628499] Key type lgssc unregistered [ 7243.727855] LNet: 355680:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7244.769263] LNet: Removed LNI 192.168.201.3@tcp [ 7245.027276] Key type .llcrypt unregistered [ 7245.028329] Key type ._llcrypt unregistered [ 7245.465690] alg: No test for adler32 (adler32-zlib) [ 7246.218296] Key type ._llcrypt registered [ 7246.219357] Key type .llcrypt registered [ 7246.288680] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7246.394561] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7246.468810] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7246.470461] LNet: Accept secure, port 988 [ 7248.056084] Key type lgssc registered [ 7248.415519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7252.425395] Lustre: DEBUG MARKER: Iteration 9 [ 7252.546579] LustreError: 356472:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7252.546644] LustreError: 356474:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7252.554773] LustreError: 356472:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7253.621405] Lustre: Mounted lustre-client [ 7253.623065] Lustre: Skipped 1 previous similar message [ 7254.173144] Lustre: Unmounted lustre-client [ 7255.218279] Key type lgssc unregistered [ 7255.310477] LNet: 356821:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7256.352890] LNet: Removed LNI 192.168.201.3@tcp [ 7256.586469] Key type .llcrypt unregistered [ 7256.588109] Key type ._llcrypt unregistered [ 7256.915030] alg: No test for adler32 (adler32-zlib) [ 7257.666370] Key type ._llcrypt registered [ 7257.667667] Key type .llcrypt registered [ 7257.753052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7257.881055] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7257.972072] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7257.973628] LNet: Accept secure, port 988 [ 7259.560099] Key type lgssc registered [ 7259.930651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7264.027611] Lustre: DEBUG MARKER: Iteration 10 [ 7264.151311] LustreError: 357613:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7264.151381] LustreError: 357614:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7264.160072] LustreError: 357613:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7265.223802] Lustre: Mounted lustre-client [ 7265.225186] Lustre: Skipped 1 previous similar message [ 7265.826073] Lustre: Unmounted lustre-client [ 7266.828326] Key type lgssc unregistered [ 7266.935915] LNet: 357964:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7268.000794] LNet: Removed LNI 192.168.201.3@tcp [ 7268.235739] Key type .llcrypt unregistered [ 7268.237548] Key type ._llcrypt unregistered [ 7268.610137] alg: No test for adler32 (adler32-zlib) [ 7269.361300] Key type ._llcrypt registered [ 7269.362645] Key type .llcrypt registered [ 7269.438595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7269.553123] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7269.632860] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7269.635432] LNet: Accept secure, port 988 [ 7271.224082] Key type lgssc registered [ 7271.597823] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7275.548615] Lustre: DEBUG MARKER: Iteration 11 [ 7275.654857] LustreError: 358757:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7275.654903] LustreError: 358758:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7275.660080] LustreError: 358757:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7276.707238] Lustre: Mounted lustre-client [ 7277.242128] Lustre: Unmounted lustre-client [ 7278.212790] Key type lgssc unregistered [ 7278.310383] LNet: 359106:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7279.328901] LNet: Removed LNI 192.168.201.3@tcp [ 7279.584967] Key type .llcrypt unregistered [ 7279.586471] Key type ._llcrypt unregistered [ 7279.888063] alg: No test for adler32 (adler32-zlib) [ 7280.640432] Key type ._llcrypt registered [ 7280.642376] Key type .llcrypt registered [ 7280.715494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7280.824761] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7280.896635] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7280.898131] LNet: Accept secure, port 988 [ 7282.480088] Key type lgssc registered [ 7282.855157] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7286.627218] Lustre: DEBUG MARKER: Iteration 12 [ 7286.726204] LustreError: 359898:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7286.726297] LustreError: 359899:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7286.733072] LustreError: 359898:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7287.789861] Lustre: Mounted lustre-client [ 7287.791294] Lustre: Skipped 1 previous similar message [ 7288.355398] Lustre: Unmounted lustre-client [ 7289.352102] Key type lgssc unregistered [ 7289.456465] LNet: 360243:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7290.465590] LNet: Removed LNI 192.168.201.3@tcp [ 7290.707093] Key type .llcrypt unregistered [ 7290.708195] Key type ._llcrypt unregistered [ 7291.092671] alg: No test for adler32 (adler32-zlib) [ 7291.844331] Key type ._llcrypt registered [ 7291.845729] Key type .llcrypt registered [ 7291.917764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7292.024681] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7292.111980] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7292.113764] LNet: Accept secure, port 988 [ 7293.696123] Key type lgssc registered [ 7294.082249] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7298.125637] Lustre: DEBUG MARKER: Iteration 13 [ 7298.260971] LustreError: 361039:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7298.261085] LustreError: 361038:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7298.268701] LustreError: 361039:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7299.319442] Lustre: Mounted lustre-client [ 7299.929600] Lustre: Unmounted lustre-client [ 7300.984462] Key type lgssc unregistered [ 7301.108783] LNet: 361386:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7302.176813] LNet: Removed LNI 192.168.201.3@tcp [ 7302.418632] Key type .llcrypt unregistered [ 7302.420262] Key type ._llcrypt unregistered [ 7302.734468] alg: No test for adler32 (adler32-zlib) [ 7303.487356] Key type ._llcrypt registered [ 7303.488543] Key type .llcrypt registered [ 7303.561476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7303.677612] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7303.758937] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7303.761652] LNet: Accept secure, port 988 [ 7305.344106] Key type lgssc registered [ 7305.720833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7309.477541] Lustre: DEBUG MARKER: Iteration 14 [ 7309.574872] LustreError: 362179:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7309.574903] LustreError: 362180:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7309.582124] LustreError: 362179:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7310.625096] Lustre: Mounted lustre-client [ 7310.626121] Lustre: Skipped 1 previous similar message [ 7311.067135] Lustre: Unmounted lustre-client [ 7311.906154] Key type lgssc unregistered [ 7312.012459] LNet: 362526:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7313.056735] LNet: Removed LNI 192.168.201.3@tcp [ 7313.272537] Key type .llcrypt unregistered [ 7313.273478] Key type ._llcrypt unregistered [ 7313.508989] alg: No test for adler32 (adler32-zlib) [ 7314.260332] Key type ._llcrypt registered [ 7314.261340] Key type .llcrypt registered [ 7314.318358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7314.417142] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7314.488031] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7314.489849] LNet: Accept secure, port 988 [ 7316.072084] Key type lgssc registered [ 7316.402159] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7319.898353] Lustre: DEBUG MARKER: Iteration 15 [ 7320.003733] LustreError: 363321:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7320.003989] LustreError: 363320:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7320.011506] LustreError: 363321:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7321.070454] Lustre: Mounted lustre-client [ 7321.071963] Lustre: Skipped 1 previous similar message [ 7321.569193] Lustre: Unmounted lustre-client [ 7322.427380] Key type lgssc unregistered [ 7322.517481] LNet: 363667:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7323.553535] LNet: Removed LNI 192.168.201.3@tcp [ 7323.818274] Key type .llcrypt unregistered [ 7323.819921] Key type ._llcrypt unregistered [ 7324.161882] alg: No test for adler32 (adler32-zlib) [ 7324.913413] Key type ._llcrypt registered [ 7324.915290] Key type .llcrypt registered [ 7324.997565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7325.119719] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7325.209789] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7325.211421] LNet: Accept secure, port 988 [ 7326.800133] Key type lgssc registered [ 7327.222769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7331.201359] Lustre: DEBUG MARKER: Iteration 16 [ 7331.300723] LustreError: 364460:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7331.301221] LustreError: 364461:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7331.305498] LustreError: 364460:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7332.356848] Lustre: Mounted lustre-client [ 7332.358122] Lustre: Skipped 1 previous similar message [ 7333.046480] Lustre: Unmounted lustre-client [ 7334.054512] Key type lgssc unregistered [ 7334.181521] LNet: 364807:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7335.200891] LNet: Removed LNI 192.168.201.3@tcp [ 7335.427697] Key type .llcrypt unregistered [ 7335.429177] Key type ._llcrypt unregistered [ 7335.706213] alg: No test for adler32 (adler32-zlib) [ 7336.457313] Key type ._llcrypt registered [ 7336.459086] Key type .llcrypt registered [ 7336.536544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7336.648232] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7336.730439] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7336.732413] LNet: Accept secure, port 988 [ 7338.320132] Key type lgssc registered [ 7338.726790] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7342.861843] Lustre: DEBUG MARKER: Iteration 17 [ 7342.971375] LustreError: 365599:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7342.971432] LustreError: 365600:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7342.979230] LustreError: 365599:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7344.025797] Lustre: Mounted lustre-client [ 7344.559478] Lustre: Unmounted lustre-client [ 7345.620151] Key type lgssc unregistered [ 7345.727292] LNet: 365947:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7346.785084] LNet: Removed LNI 192.168.201.3@tcp [ 7347.048805] Key type .llcrypt unregistered [ 7347.049863] Key type ._llcrypt unregistered [ 7347.563897] alg: No test for adler32 (adler32-zlib) [ 7348.316354] Key type ._llcrypt registered [ 7348.317980] Key type .llcrypt registered [ 7348.395789] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7348.521383] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7348.612251] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7348.613915] LNet: Accept secure, port 988 [ 7350.200095] Key type lgssc registered [ 7350.597180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7354.606367] Lustre: DEBUG MARKER: Iteration 18 [ 7354.705308] LustreError: 366740:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7354.705338] LustreError: 366741:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7354.713416] LustreError: 366740:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7355.762555] Lustre: Mounted lustre-client [ 7356.386702] Lustre: Unmounted lustre-client [ 7356.388148] Lustre: Skipped 1 previous similar message [ 7357.300078] Key type lgssc unregistered [ 7357.399368] LNet: 367092:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7358.432742] LNet: Removed LNI 192.168.201.3@tcp [ 7358.637405] Key type .llcrypt unregistered [ 7358.638650] Key type ._llcrypt unregistered [ 7358.881134] alg: No test for adler32 (adler32-zlib) [ 7359.634284] Key type ._llcrypt registered [ 7359.635132] Key type .llcrypt registered [ 7359.698436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7359.788406] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7359.856831] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7359.858450] LNet: Accept secure, port 988 [ 7361.432148] Key type lgssc registered [ 7361.781075] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7365.253606] Lustre: DEBUG MARKER: Iteration 19 [ 7365.363257] LustreError: 367886:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7365.363336] LustreError: 367885:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7365.369477] LustreError: 367886:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7366.420956] Lustre: Mounted lustre-client [ 7366.422125] Lustre: Skipped 1 previous similar message [ 7366.905836] Lustre: Unmounted lustre-client [ 7367.780423] Key type lgssc unregistered [ 7367.877040] LNet: 368233:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7368.928736] LNet: Removed LNI 192.168.201.3@tcp [ 7369.137441] Key type .llcrypt unregistered [ 7369.138478] Key type ._llcrypt unregistered [ 7369.339722] alg: No test for adler32 (adler32-zlib) [ 7370.096418] Key type ._llcrypt registered [ 7370.098138] Key type .llcrypt registered [ 7370.172288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7370.275657] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7370.356465] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7370.358148] LNet: Accept secure, port 988 [ 7371.944088] Key type lgssc registered [ 7372.272431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7375.858273] Lustre: DEBUG MARKER: Iteration 20 [ 7375.952380] LustreError: 369026:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7375.955096] LustreError: 369027:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7375.959069] LustreError: 369026:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7377.022083] Lustre: Mounted lustre-client [ 7377.023501] Lustre: Skipped 1 previous similar message [ 7377.551182] Lustre: Unmounted lustre-client [ 7378.557639] Key type lgssc unregistered [ 7378.663501] LNet: 369377:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7379.681384] LNet: Removed LNI 192.168.201.3@tcp [ 7379.918885] Key type .llcrypt unregistered [ 7379.920255] Key type ._llcrypt unregistered [ 7380.178054] alg: No test for adler32 (adler32-zlib) [ 7380.930400] Key type ._llcrypt registered [ 7380.932088] Key type .llcrypt registered [ 7380.990866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7381.084987] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7381.151133] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7381.152725] LNet: Accept secure, port 988 [ 7382.728103] Key type lgssc registered [ 7383.061751] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7386.623784] Lustre: DEBUG MARKER: Iteration 21 [ 7386.724165] LustreError: 370170:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7386.724200] LustreError: 370171:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7386.729306] LustreError: 370170:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7387.775197] Lustre: Mounted lustre-client [ 7387.776148] Lustre: Skipped 1 previous similar message [ 7388.262776] Lustre: Unmounted lustre-client [ 7389.232683] Key type lgssc unregistered [ 7389.338473] LNet: 370517:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7390.369499] LNet: Removed LNI 192.168.201.3@tcp [ 7390.606444] Key type .llcrypt unregistered [ 7390.607977] Key type ._llcrypt unregistered [ 7390.983807] alg: No test for adler32 (adler32-zlib) [ 7391.736256] Key type ._llcrypt registered [ 7391.737273] Key type .llcrypt registered [ 7391.804206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7391.924639] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7392.002750] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7392.004636] LNet: Accept secure, port 988 [ 7393.584118] Key type lgssc registered [ 7394.001418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7398.032617] Lustre: DEBUG MARKER: Iteration 22 [ 7398.155903] LustreError: 371308:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7398.161710] LustreError: 371310:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7398.164337] LustreError: 371308:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7399.225625] Lustre: Mounted lustre-client [ 7399.226842] Lustre: Skipped 1 previous similar message [ 7399.841151] Lustre: Unmounted lustre-client [ 7399.842413] Lustre: Skipped 1 previous similar message [ 7400.852603] Key type lgssc unregistered [ 7400.953538] LNet: 371660:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7402.016834] LNet: Removed LNI 192.168.201.3@tcp [ 7402.269438] Key type .llcrypt unregistered [ 7402.270681] Key type ._llcrypt unregistered [ 7402.623781] alg: No test for adler32 (adler32-zlib) [ 7403.375329] Key type ._llcrypt registered [ 7403.376371] Key type .llcrypt registered [ 7403.466277] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7403.582657] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7403.666219] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7403.668169] LNet: Accept secure, port 988 [ 7405.256088] Key type lgssc registered [ 7405.610152] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7409.427584] Lustre: DEBUG MARKER: Iteration 23 [ 7409.525683] LustreError: 372453:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7409.525865] LustreError: 372454:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7409.529876] LustreError: 372453:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7410.579944] Lustre: Mounted lustre-client [ 7410.580974] Lustre: Skipped 1 previous similar message [ 7411.150901] Lustre: Unmounted lustre-client [ 7411.152045] Lustre: Skipped 1 previous similar message [ 7412.079297] Key type lgssc unregistered [ 7412.176422] LNet: 372804:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7413.216829] LNet: Removed LNI 192.168.201.3@tcp [ 7413.437069] Key type .llcrypt unregistered [ 7413.438141] Key type ._llcrypt unregistered [ 7413.729867] alg: No test for adler32 (adler32-zlib) [ 7414.482317] Key type ._llcrypt registered [ 7414.483504] Key type .llcrypt registered [ 7414.550821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7414.648030] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7414.731897] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7414.734690] LNet: Accept secure, port 988 [ 7416.320141] Key type lgssc registered [ 7416.748080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7420.641845] Lustre: DEBUG MARKER: Iteration 24 [ 7420.783197] LustreError: 373598:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7420.783211] LustreError: 373599:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7420.788656] LustreError: 373598:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7421.851920] Lustre: Mounted lustre-client [ 7421.853182] Lustre: Skipped 1 previous similar message [ 7422.475707] Lustre: Unmounted lustre-client [ 7423.443430] Key type lgssc unregistered [ 7423.552377] LNet: 373948:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7424.608721] LNet: Removed LNI 192.168.201.3@tcp [ 7424.834564] Key type .llcrypt unregistered [ 7424.835573] Key type ._llcrypt unregistered [ 7425.193793] alg: No test for adler32 (adler32-zlib) [ 7425.945451] Key type ._llcrypt registered [ 7425.946624] Key type .llcrypt registered [ 7426.028412] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7426.164054] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7426.245207] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7426.246716] LNet: Accept secure, port 988 [ 7427.832119] Key type lgssc registered [ 7428.202523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7432.018615] Lustre: DEBUG MARKER: Iteration 25 [ 7432.146478] LustreError: 374740:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7432.148135] LustreError: 374741:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7432.152522] LustreError: 374740:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7433.214442] Lustre: Mounted lustre-client [ 7433.216199] Lustre: Skipped 1 previous similar message [ 7433.756110] Lustre: Unmounted lustre-client [ 7433.757096] Lustre: Skipped 1 previous similar message [ 7434.650420] Key type lgssc unregistered [ 7434.744464] LNet: 375087:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7435.808792] LNet: Removed LNI 192.168.201.3@tcp [ 7436.033687] Key type .llcrypt unregistered [ 7436.035401] Key type ._llcrypt unregistered [ 7436.291811] alg: No test for adler32 (adler32-zlib) [ 7437.044326] Key type ._llcrypt registered [ 7437.045331] Key type .llcrypt registered [ 7437.114234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7437.223237] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7437.295103] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7437.296636] LNet: Accept secure, port 988 [ 7438.880127] Key type lgssc registered [ 7439.237264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7442.897269] Lustre: DEBUG MARKER: Iteration 26 [ 7443.009848] LustreError: 375881:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7443.009881] LustreError: 375880:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7443.017646] LustreError: 375881:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [ 7444.073132] Lustre: Mounted lustre-client [ 7444.074247] Lustre: Skipped 1 previous similar message [ 7444.595176] Lustre: Unmounted lustre-client [ 7445.632038] Key type lgssc unregistered [ 7445.730459] LNet: 376231:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7446.752947] LNet: Removed LNI 192.168.201.3@tcp [ 7447.004638] Key type .llcrypt unregistered [ 7447.005740] Key type ._llcrypt unregistered [ 7447.419882] alg: No test for adler32 (adler32-zlib) [ 7448.172325] Key type ._llcrypt registered [ 7448.173316] Key type .llcrypt registered [ 7448.234210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7448.353341] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7448.426577] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7448.428263] LNet: Accept secure, port 988 [ 7450.008071] Key type lgssc registered [ 7450.338931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7453.991386] Lustre: DEBUG MARKER: Iteration 27 [ 7454.097349] LustreError: 377025:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7454.097407] LustreError: 377026:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7454.104714] LustreError: 377025:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7455.170406] Lustre: Mounted lustre-client [ 7455.171363] Lustre: Skipped 1 previous similar message [ 7455.822548] Lustre: Unmounted lustre-client [ 7456.785873] Key type lgssc unregistered [ 7456.877514] LNet: 377372:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7457.888716] LNet: Removed LNI 192.168.201.3@tcp [ 7458.126330] Key type .llcrypt unregistered [ 7458.127609] Key type ._llcrypt unregistered [ 7458.526439] alg: No test for adler32 (adler32-zlib) [ 7459.280330] Key type ._llcrypt registered [ 7459.281304] Key type .llcrypt registered [ 7459.345797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7459.447696] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7459.527989] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7459.529508] LNet: Accept secure, port 988 [ 7461.112132] Key type lgssc registered [ 7461.491546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7465.075968] Lustre: DEBUG MARKER: Iteration 28 [ 7465.206047] LustreError: 378165:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7465.209221] LustreError: 378166:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7465.214289] LustreError: 378165:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7466.268640] Lustre: Mounted lustre-client [ 7466.874149] Lustre: Unmounted lustre-client [ 7467.952839] Key type lgssc unregistered [ 7468.073695] LNet: 378515:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7469.088627] LNet: Removed LNI 192.168.201.3@tcp [ 7469.359695] Key type .llcrypt unregistered [ 7469.361198] Key type ._llcrypt unregistered [ 7469.779052] alg: No test for adler32 (adler32-zlib) [ 7470.531384] Key type ._llcrypt registered [ 7470.532514] Key type .llcrypt registered [ 7470.612206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7470.723839] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7470.805219] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7470.806836] LNet: Accept secure, port 988 [ 7472.400108] Key type lgssc registered [ 7472.790116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7476.776854] Lustre: DEBUG MARKER: Iteration 29 [ 7476.889585] LustreError: 379307:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7476.889866] LustreError: 379308:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7476.898018] LustreError: 379307:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7477.955449] Lustre: Mounted lustre-client [ 7478.544255] Lustre: Unmounted lustre-client [ 7479.631670] Key type lgssc unregistered [ 7479.738589] LNet: 379657:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7480.800747] LNet: Removed LNI 192.168.201.3@tcp [ 7481.050403] Key type .llcrypt unregistered [ 7481.052106] Key type ._llcrypt unregistered [ 7481.402450] alg: No test for adler32 (adler32-zlib) [ 7482.154373] Key type ._llcrypt registered [ 7482.156098] Key type .llcrypt registered [ 7482.247866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7482.380442] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7482.465994] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7482.468022] LNet: Accept secure, port 988 [ 7484.056091] Key type lgssc registered [ 7484.444252] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7488.299944] Lustre: DEBUG MARKER: Iteration 30 [ 7488.406287] LustreError: 380450:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7488.406525] LustreError: 380451:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7488.412914] LustreError: 380450:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7489.468340] Lustre: Mounted lustre-client [ 7489.470371] Lustre: Skipped 1 previous similar message [ 7490.035134] Lustre: Unmounted lustre-client [ 7490.036296] Lustre: Skipped 1 previous similar message [ 7491.073214] Key type lgssc unregistered [ 7491.194674] LNet: 380799:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7492.256823] LNet: Removed LNI 192.168.201.3@tcp [ 7492.496961] Key type .llcrypt unregistered [ 7492.497995] Key type ._llcrypt unregistered [ 7492.783095] alg: No test for adler32 (adler32-zlib) [ 7493.538424] Key type ._llcrypt registered [ 7493.539904] Key type .llcrypt registered [ 7493.631791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7493.750389] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7493.841046] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7493.842991] LNet: Accept secure, port 988 [ 7495.432145] Key type lgssc registered [ 7495.818860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7499.579087] Lustre: DEBUG MARKER: Iteration 31 [ 7499.707885] LustreError: 381592:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7499.716129] LustreError: 381593:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7499.718976] LustreError: 381592:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7500.781897] Lustre: Mounted lustre-client [ 7501.452338] Lustre: Unmounted lustre-client [ 7502.485483] Key type lgssc unregistered [ 7502.596476] LNet: 381939:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7503.649598] LNet: Removed LNI 192.168.201.3@tcp [ 7503.907732] Key type .llcrypt unregistered [ 7503.908852] Key type ._llcrypt unregistered [ 7504.299036] alg: No test for adler32 (adler32-zlib) [ 7505.050551] Key type ._llcrypt registered [ 7505.052880] Key type .llcrypt registered [ 7505.129244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7505.241221] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7505.326041] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7505.328607] LNet: Accept secure, port 988 [ 7506.920165] Key type lgssc registered [ 7507.288302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7511.301763] Lustre: DEBUG MARKER: Iteration 32 [ 7511.420528] LustreError: 382732:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7511.420763] LustreError: 382733:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7511.425056] LustreError: 382732:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7512.493323] Lustre: Mounted lustre-client [ 7512.494720] Lustre: Skipped 1 previous similar message [ 7513.152634] Lustre: Unmounted lustre-client [ 7513.153734] Lustre: Skipped 1 previous similar message [ 7514.196629] Key type lgssc unregistered [ 7514.309337] LNet: 383082:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7515.360728] LNet: Removed LNI 192.168.201.3@tcp [ 7515.594217] Key type .llcrypt unregistered [ 7515.595934] Key type ._llcrypt unregistered [ 7515.904270] alg: No test for adler32 (adler32-zlib) [ 7516.656343] Key type ._llcrypt registered [ 7516.657558] Key type .llcrypt registered [ 7516.737817] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7516.852354] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7516.930846] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7516.932341] LNet: Accept secure, port 988 [ 7518.512110] Key type lgssc registered [ 7518.931705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7523.146957] Lustre: DEBUG MARKER: Iteration 33 [ 7523.278724] LustreError: 383875:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7523.278919] LustreError: 383876:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7523.285573] LustreError: 383875:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7524.353970] Lustre: Mounted lustre-client [ 7524.904708] Lustre: Unmounted lustre-client [ 7525.909879] Key type lgssc unregistered [ 7526.003509] LNet: 384223:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7527.009128] LNet: Removed LNI 192.168.201.3@tcp [ 7527.263874] Key type .llcrypt unregistered [ 7527.265510] Key type ._llcrypt unregistered [ 7527.595329] alg: No test for adler32 (adler32-zlib) [ 7528.348403] Key type ._llcrypt registered [ 7528.349436] Key type .llcrypt registered [ 7528.433994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7528.572421] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7528.649829] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7528.651323] LNet: Accept secure, port 988 [ 7530.240124] Key type lgssc registered [ 7530.658804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7534.536793] Lustre: DEBUG MARKER: Iteration 34 [ 7534.639890] LustreError: 385016:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7534.640092] LustreError: 385017:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7534.647594] LustreError: 385016:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7535.699370] Lustre: Mounted lustre-client [ 7536.386172] Lustre: Unmounted lustre-client [ 7537.425752] Key type lgssc unregistered [ 7537.542480] LNet: 385365:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7538.592884] LNet: Removed LNI 192.168.201.3@tcp [ 7538.833891] Key type .llcrypt unregistered [ 7538.835169] Key type ._llcrypt unregistered [ 7539.197804] alg: No test for adler32 (adler32-zlib) [ 7539.949467] Key type ._llcrypt registered [ 7539.950674] Key type .llcrypt registered [ 7540.040346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7540.164243] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7540.254598] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7540.257274] LNet: Accept secure, port 988 [ 7541.848141] Key type lgssc registered [ 7542.233656] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7546.160556] Lustre: DEBUG MARKER: Iteration 35 [ 7546.298285] LustreError: 386157:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7546.298327] LustreError: 386159:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7546.307494] LustreError: 386157:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7547.362316] Lustre: Mounted lustre-client [ 7548.063843] Lustre: Unmounted lustre-client [ 7549.127836] Key type lgssc unregistered [ 7549.233404] LNet: 386505:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7550.241673] LNet: Removed LNI 192.168.201.3@tcp [ 7550.500611] Key type .llcrypt unregistered [ 7550.502263] Key type ._llcrypt unregistered [ 7550.824064] alg: No test for adler32 (adler32-zlib) [ 7551.576364] Key type ._llcrypt registered [ 7551.577972] Key type .llcrypt registered [ 7551.666586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7551.781611] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7551.872315] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7551.874854] LNet: Accept secure, port 988 [ 7553.464061] Key type lgssc registered [ 7553.905344] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7558.140325] Lustre: DEBUG MARKER: Iteration 36 [ 7558.253797] LustreError: 387297:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7558.254424] LustreError: 387298:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7558.260475] LustreError: 387297:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7559.324342] Lustre: Mounted lustre-client [ 7559.325399] Lustre: Skipped 1 previous similar message [ 7560.007990] Lustre: Unmounted lustre-client [ 7561.013110] Key type lgssc unregistered [ 7561.141635] LNet: 387648:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7562.209421] LNet: Removed LNI 192.168.201.3@tcp [ 7562.453114] Key type .llcrypt unregistered [ 7562.454663] Key type ._llcrypt unregistered [ 7562.806574] alg: No test for adler32 (adler32-zlib) [ 7563.559315] Key type ._llcrypt registered [ 7563.560409] Key type .llcrypt registered [ 7563.637042] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7563.752158] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7563.839395] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7563.841777] LNet: Accept secure, port 988 [ 7565.432118] Key type lgssc registered [ 7565.807044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7569.947919] Lustre: DEBUG MARKER: Iteration 37 [ 7570.074905] LustreError: 388441:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7570.075137] LustreError: 388442:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7570.082301] LustreError: 388441:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7571.142900] Lustre: Mounted lustre-client [ 7571.144287] Lustre: Skipped 1 previous similar message [ 7571.771202] Lustre: Unmounted lustre-client [ 7572.828255] Key type lgssc unregistered [ 7572.941607] LNet: 388785:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7573.984965] LNet: Removed LNI 192.168.201.3@tcp [ 7574.226790] Key type .llcrypt unregistered [ 7574.228456] Key type ._llcrypt unregistered [ 7574.502600] alg: No test for adler32 (adler32-zlib) [ 7575.254326] Key type ._llcrypt registered [ 7575.255314] Key type .llcrypt registered [ 7575.329827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7575.441289] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7575.528698] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7575.531307] LNet: Accept secure, port 988 [ 7577.120169] Key type lgssc registered [ 7577.544510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7581.494409] Lustre: DEBUG MARKER: Iteration 38 [ 7581.625521] LustreError: 389577:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7581.625843] LustreError: 389578:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7581.631392] LustreError: 389577:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7582.697607] Lustre: Mounted lustre-client [ 7582.699217] Lustre: Skipped 1 previous similar message [ 7583.333141] Lustre: Unmounted lustre-client [ 7584.398898] Key type lgssc unregistered [ 7584.507308] LNet: 389926:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7585.568812] LNet: Removed LNI 192.168.201.3@tcp [ 7585.828220] Key type .llcrypt unregistered [ 7585.829204] Key type ._llcrypt unregistered [ 7586.131086] alg: No test for adler32 (adler32-zlib) [ 7586.882369] Key type ._llcrypt registered [ 7586.883256] Key type .llcrypt registered [ 7586.961658] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7587.068749] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7587.144825] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7587.146467] LNet: Accept secure, port 988 [ 7588.728087] Key type lgssc registered [ 7589.118638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7592.937354] Lustre: DEBUG MARKER: Iteration 39 [ 7593.036774] LustreError: 390720:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7593.036819] LustreError: 390721:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7593.043276] LustreError: 390720:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7594.097661] Lustre: Mounted lustre-client [ 7594.748233] Lustre: Unmounted lustre-client [ 7595.743381] Key type lgssc unregistered [ 7595.851402] LNet: 391068:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7596.896924] LNet: Removed LNI 192.168.201.3@tcp [ 7597.135108] Key type .llcrypt unregistered [ 7597.136826] Key type ._llcrypt unregistered [ 7597.418977] alg: No test for adler32 (adler32-zlib) [ 7598.171345] Key type ._llcrypt registered [ 7598.172509] Key type .llcrypt registered [ 7598.247459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7598.361610] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7598.442866] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7598.445195] LNet: Accept secure, port 988 [ 7600.032095] Key type lgssc registered [ 7600.397700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7604.461091] Lustre: DEBUG MARKER: Iteration 40 [ 7604.587450] LustreError: 391860:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7604.587952] LustreError: 391862:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7604.596513] LustreError: 391860:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7605.654762] Lustre: Mounted lustre-client [ 7605.656638] Lustre: Skipped 1 previous similar message [ 7606.198306] Lustre: Unmounted lustre-client [ 7607.252250] Key type lgssc unregistered [ 7607.360339] LNet: 392212:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.417932] LNet: Removed LNI 192.168.201.3@tcp [ 7608.671658] Key type .llcrypt unregistered [ 7608.672722] Key type ._llcrypt unregistered [ 7608.989305] alg: No test for adler32 (adler32-zlib) [ 7609.741279] Key type ._llcrypt registered [ 7609.742283] Key type .llcrypt registered [ 7609.814761] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7609.923648] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7610.004401] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7610.006152] LNet: Accept secure, port 988 [ 7611.592109] Key type lgssc registered [ 7611.968695] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7615.779437] Lustre: DEBUG MARKER: Iteration 41 [ 7615.894509] LustreError: 393006:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7615.894570] LustreError: 393007:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7615.901048] LustreError: 393006:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7616.947276] Lustre: Mounted lustre-client [ 7616.948938] Lustre: Skipped 1 previous similar message [ 7617.524551] Lustre: Unmounted lustre-client [ 7618.465236] Key type lgssc unregistered [ 7618.567519] LNet: 393356:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7619.616794] LNet: Removed LNI 192.168.201.3@tcp [ 7619.849093] Key type .llcrypt unregistered [ 7619.850804] Key type ._llcrypt unregistered [ 7620.111183] alg: No test for adler32 (adler32-zlib) [ 7620.862320] Key type ._llcrypt registered [ 7620.863277] Key type .llcrypt registered [ 7620.932598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7621.035071] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7621.111488] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7621.113189] LNet: Accept secure, port 988 [ 7622.688061] Key type lgssc registered [ 7623.029725] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7626.709325] Lustre: DEBUG MARKER: Iteration 42 [ 7626.818360] LustreError: 394165:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7626.822159] LustreError: 394166:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7626.826527] LustreError: 394165:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [ 7627.875943] Lustre: Mounted lustre-client [ 7628.482516] Lustre: Unmounted lustre-client [ 7629.357567] Key type lgssc unregistered [ 7629.455525] LNet: 394513:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7630.496572] LNet: Removed LNI 192.168.201.3@tcp [ 7630.700081] Key type .llcrypt unregistered [ 7630.701333] Key type ._llcrypt unregistered [ 7630.948973] alg: No test for adler32 (adler32-zlib) [ 7631.701319] Key type ._llcrypt registered [ 7631.702299] Key type .llcrypt registered [ 7631.761318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7631.848185] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7631.915110] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7631.916584] LNet: Accept secure, port 988 [ 7633.488116] Key type lgssc registered [ 7633.802933] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7637.517353] Lustre: DEBUG MARKER: Iteration 43 [ 7637.640426] LustreError: 395305:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7637.640481] LustreError: 395306:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7637.644682] LustreError: 395305:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7638.692489] Lustre: Mounted lustre-client [ 7638.694212] Lustre: Skipped 1 previous similar message [ 7639.371737] Lustre: Unmounted lustre-client [ 7640.377607] Key type lgssc unregistered [ 7640.479494] LNet: 395653:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7641.504839] LNet: Removed LNI 192.168.201.3@tcp [ 7641.716879] Key type .llcrypt unregistered [ 7641.717914] Key type ._llcrypt unregistered [ 7642.000407] alg: No test for adler32 (adler32-zlib) [ 7642.756298] Key type ._llcrypt registered [ 7642.757318] Key type .llcrypt registered [ 7642.828140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7642.947358] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7643.032056] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7643.033645] LNet: Accept secure, port 988 [ 7644.624094] Key type lgssc registered [ 7644.972120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7648.469588] Lustre: DEBUG MARKER: Iteration 44 [ 7648.588619] LustreError: 396457:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7648.588643] LustreError: 396459:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7648.594341] LustreError: 396457:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7649.644277] Lustre: Mounted lustre-client [ 7650.190340] Lustre: Unmounted lustre-client [ 7651.249498] Key type lgssc unregistered [ 7651.368630] LNet: 396802:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7652.385712] LNet: Removed LNI 192.168.201.3@tcp [ 7652.631749] Key type .llcrypt unregistered [ 7652.633072] Key type ._llcrypt unregistered [ 7652.959949] alg: No test for adler32 (adler32-zlib) [ 7653.712320] Key type ._llcrypt registered [ 7653.713367] Key type .llcrypt registered [ 7653.774012] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7653.873573] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7653.953923] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7653.955354] LNet: Accept secure, port 988 [ 7655.536146] Key type lgssc registered [ 7655.985777] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7659.721739] Lustre: DEBUG MARKER: Iteration 45 [ 7659.822259] LustreError: 397596:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7659.822486] LustreError: 397595:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7659.827994] LustreError: 397596:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4999 [ 7660.880653] Lustre: Mounted lustre-client [ 7660.881834] Lustre: Skipped 1 previous similar message [ 7661.482828] Lustre: Unmounted lustre-client [ 7662.464739] Key type lgssc unregistered [ 7662.575465] LNet: 397940:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7663.585440] LNet: Removed LNI 192.168.201.3@tcp [ 7663.820402] Key type .llcrypt unregistered [ 7663.821929] Key type ._llcrypt unregistered [ 7664.193167] alg: No test for adler32 (adler32-zlib) [ 7664.961310] Key type ._llcrypt registered [ 7664.962363] Key type .llcrypt registered [ 7665.039960] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7665.165756] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7665.254527] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7665.256638] LNet: Accept secure, port 988 [ 7666.848155] Key type lgssc registered [ 7667.282623] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7671.004330] Lustre: DEBUG MARKER: Iteration 46 [ 7671.140103] LustreError: 398735:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7671.140169] LustreError: 398736:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7671.144974] LustreError: 398735:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7672.197704] Lustre: Mounted lustre-client [ 7672.199507] Lustre: Skipped 1 previous similar message [ 7672.781442] Lustre: Unmounted lustre-client [ 7673.835212] Key type lgssc unregistered [ 7673.952757] LNet: 399083:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7674.977496] LNet: Removed LNI 192.168.201.3@tcp [ 7675.230615] Key type .llcrypt unregistered [ 7675.232019] Key type ._llcrypt unregistered [ 7675.506831] alg: No test for adler32 (adler32-zlib) [ 7676.258318] Key type ._llcrypt registered [ 7676.259335] Key type .llcrypt registered [ 7676.318275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7676.406180] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7676.476417] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7676.478070] LNet: Accept secure, port 988 [ 7678.056232] Key type lgssc registered [ 7678.468838] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7681.989688] Lustre: DEBUG MARKER: Iteration 47 [ 7682.093471] LustreError: 399877:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7682.094109] LustreError: 399878:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7682.100284] LustreError: 399877:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [ 7683.152507] Lustre: Mounted lustre-client [ 7683.727279] Lustre: Unmounted lustre-client [ 7684.680274] Key type lgssc unregistered [ 7684.782491] LNet: 400226:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7685.793762] LNet: Removed LNI 192.168.201.3@tcp [ 7686.038940] Key type .llcrypt unregistered [ 7686.040291] Key type ._llcrypt unregistered [ 7686.465395] alg: No test for adler32 (adler32-zlib) [ 7687.245532] Key type ._llcrypt registered [ 7687.246913] Key type .llcrypt registered [ 7687.331941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7687.462878] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7687.556521] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7687.558238] LNet: Accept secure, port 988 [ 7689.144073] Key type lgssc registered [ 7689.512354] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7692.964742] Lustre: DEBUG MARKER: Iteration 48 [ 7693.072660] LustreError: 401017:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7693.077406] LustreError: 401021:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7693.079652] LustreError: 401017:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [ 7694.131273] Lustre: Mounted lustre-client [ 7694.132321] Lustre: Skipped 1 previous similar message [ 7694.680261] Lustre: Unmounted lustre-client [ 7695.558149] Key type lgssc unregistered [ 7695.653508] LNet: 401364:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7696.672844] LNet: Removed LNI 192.168.201.3@tcp [ 7696.931947] Key type .llcrypt unregistered [ 7696.933036] Key type ._llcrypt unregistered [ 7697.246613] alg: No test for adler32 (adler32-zlib) [ 7697.998307] Key type ._llcrypt registered [ 7697.999267] Key type .llcrypt registered [ 7698.068693] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7698.166854] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7698.244497] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7698.245992] LNet: Accept secure, port 988 [ 7699.824115] Key type lgssc registered [ 7700.208886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7703.826247] Lustre: DEBUG MARKER: Iteration 49 [ 7703.938024] LustreError: 402156:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7703.938059] LustreError: 402157:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7703.942574] LustreError: 402156:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4998 [ 7704.995794] Lustre: Mounted lustre-client [ 7705.664247] Lustre: Unmounted lustre-client [ 7706.850408] Key type lgssc unregistered [ 7706.965711] LNet: 402508:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7708.001075] LNet: Removed LNI 192.168.201.3@tcp [ 7708.250905] Key type .llcrypt unregistered [ 7708.252142] Key type ._llcrypt unregistered [ 7708.572305] alg: No test for adler32 (adler32-zlib) [ 7709.323526] Key type ._llcrypt registered [ 7709.325312] Key type .llcrypt registered [ 7709.416115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7709.546983] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7709.632721] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7709.634422] LNet: Accept secure, port 988 [ 7711.224129] Key type lgssc registered [ 7711.614503] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7715.171308] Lustre: DEBUG MARKER: Iteration 50 [ 7715.274785] LustreError: 403301:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [ 7715.275203] LustreError: 403302:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [ 7715.282370] LustreError: 403301:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [ 7716.331145] Lustre: Mounted lustre-client [ 7716.834125] Lustre: Unmounted lustre-client [ 7717.718782] Key type lgssc unregistered [ 7717.824528] LNet: 403648:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7718.880883] LNet: Removed LNI 192.168.201.3@tcp [ 7719.105852] Key type .llcrypt unregistered [ 7719.107507] Key type ._llcrypt unregistered [ 7719.338462] alg: No test for adler32 (adler32-zlib) [ 7720.090295] Key type ._llcrypt registered [ 7720.091282] Key type .llcrypt registered [ 7720.150945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7720.254441] Lustre: Lustre: Build Version: 2.15.8_1_gd7491a2 [ 7720.333682] LNet: Added LNI 192.168.201.3@tcp [8/256/0/180] [ 7720.335374] LNet: Accept secure, port 988 [ 7721.912114] Key type lgssc registered [ 7722.269950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7726.716934] Lustre: Mounted lustre-client [ 7729.204540] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 09:20:58 (1764944458) [ 7736.800105] Lustre: 404952:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1764944459/real 1764944459] req@00000000fe53f1a9 x1850678390559488/t0(0) o36->lustre-MDT0000-mdc-ffff892c8555b000@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1764944466 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [ 7736.806710] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7736.816773] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection restored to (at 192.168.201.103@tcp) [ 7743.968142] Lustre: 404952:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1764944466/real 1764944466] req@00000000fe53f1a9 x1850678390559488/t0(0) o36->lustre-MDT0000-mdc-ffff892c8555b000@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1764944473 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7743.975085] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7743.983740] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection restored to (at 192.168.201.103@tcp) [ 7751.136178] Lustre: 404952:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1764944473/real 1764944473] req@00000000fe53f1a9 x1850678390559488/t0(0) o36->lustre-MDT0000-mdc-ffff892c8555b000@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1764944480 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7751.141907] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7751.150591] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection restored to (at 192.168.201.103@tcp) [ 7758.304106] Lustre: 404952:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1764944480/real 1764944480] req@00000000fe53f1a9 x1850678390559488/t0(0) o36->lustre-MDT0000-mdc-ffff892c8555b000@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1764944487 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7758.315705] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7758.329650] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection restored to (at 192.168.201.103@tcp) [ 7765.472165] Lustre: 404952:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1764944487/real 1764944487] req@00000000fe53f1a9 x1850678390559488/t0(0) o36->lustre-MDT0000-mdc-ffff892c8555b000@192.168.201.103@tcp:12/10 lens 496/440 e 0 to 1 dl 1764944494 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [ 7765.479163] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection to lustre-MDT0000 (at 192.168.201.103@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7765.488912] Lustre: lustre-MDT0000-mdc-ffff892c8555b000: Connection restored to (at 192.168.201.103@tcp) [ 7766.165518] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 09:21:35 (1764944495) [ 7772.024574] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 09:21:40 (1764944500) [ 7775.355127] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 09:21:44 (1764944504) [ 7777.554786] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 7778.172540] Lustre: DEBUG MARKER: == sanityn test complete, duration 7546 sec ============== 09:21:47 (1764944507) [ 7828.022436] Lustre: Unmounted lustre-client [ 7857.783276] Key type lgssc unregistered [ 7857.874586] LNet: 408210:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7858.913545] LNet: Removed LNI 192.168.201.3@tcp [ 7859.264491] Key type .llcrypt unregistered [ 7859.266195] Key type ._llcrypt unregistered