[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 458625253 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002380] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004015] kvm-guest: setup PV IPIs [ 0.007458] ..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.008022] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009025] pid_max: default: 32768 minimum: 301 [ 0.010141] LSM: Security Framework initializing [ 0.011077] Yama: becoming mindful. [ 0.012041] SELinux: Initializing. [ 0.013078] *** VALIDATE selinux *** [ 0.021629] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026404] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028109] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029119] *** VALIDATE tmpfs *** [ 0.031351] *** VALIDATE proc *** [ 0.032263] *** VALIDATE cgroup *** [ 0.033011] *** VALIDATE cgroup2 *** [ 0.034307] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035201] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037031] Spectre V2 : User space: Vulnerable [ 0.038014] Speculative Store Bypass: Vulnerable [ 0.041272] debug: unmapping init [mem 0xffffffff89a59000-0xffffffff89a60fff] [ 0.044336] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045756] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046025] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048012] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050018] ... max period: 00007fffffffffff [ 0.051018] ... fixed-purpose events: 3 [ 0.052014] ... event mask: 000000070000000f [ 0.053296] rcu: Hierarchical SRCU implementation. [ 0.055497] smp: Bringing up secondary CPUs ... [ 0.056654] x86: Booting SMP configuration: [ 0.057030] .... node #0, CPUs: #1 #2 #3 [ 0.060465] smp: Brought up 1 node, 4 CPUs [ 0.062014] smpboot: Max logical packages: 1 [ 0.063019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.137000] node 0 deferred pages initialised in 72ms [ 0.141099] devtmpfs: initialized [ 0.142304] x86/mm: Memory block size: 128MB [ 0.145488] gcov: version magic: 0x41383552 [ 0.150193] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.153141] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.156336] pinctrl core: initialized pinctrl subsystem [ 0.158210] [ 0.158782] ************************************************************* [ 0.161013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.164014] ** ** [ 0.166013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.169012] ** ** [ 0.171013] ** This means that this kernel is built to expose internal ** [ 0.174015] ** IOMMU data structures, which may compromise security on ** [ 0.176014] ** your system. ** [ 0.179015] ** ** [ 0.181013] ** If you see this message and you are not debugging the ** [ 0.184015] ** kernel, report this immediately to your vendor! ** [ 0.186014] ** ** [ 0.189013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.191012] ************************************************************* [ 0.193673] NET: Registered protocol family 16 [ 0.196504] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.199065] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.201081] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.203414] cpuidle: using governor menu [ 0.204425] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.206672] PCI: Using configuration type 1 for base access [ 0.209136] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217099] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.220033] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.223104] cryptd: max_cpu_qlen set to 1000 [ 0.225204] ACPI: Added _OSI(Module Device) [ 0.227016] ACPI: Added _OSI(Processor Device) [ 0.229013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.230015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.236246] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.241545] ACPI: Interpreter enabled [ 0.242064] ACPI: PM: (supports S0 S3 S4 S5) [ 0.243013] ACPI: Using IOAPIC for interrupt routing [ 0.245095] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.248381] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.254225] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.255044] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.257019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.258085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.261370] acpiphp: Slot [2] registered [ 0.263108] acpiphp: Slot [5] registered [ 0.264116] acpiphp: Slot [6] registered [ 0.266107] acpiphp: Slot [3] registered [ 0.267124] acpiphp: Slot [4] registered [ 0.268091] acpiphp: Slot [7] registered [ 0.269097] acpiphp: Slot [8] registered [ 0.271106] acpiphp: Slot [9] registered [ 0.272091] acpiphp: Slot [10] registered [ 0.273091] acpiphp: Slot [11] registered [ 0.275111] acpiphp: Slot [12] registered [ 0.276113] acpiphp: Slot [13] registered [ 0.278098] acpiphp: Slot [14] registered [ 0.280085] acpiphp: Slot [15] registered [ 0.281145] acpiphp: Slot [16] registered [ 0.283119] acpiphp: Slot [17] registered [ 0.285098] acpiphp: Slot [18] registered [ 0.286095] acpiphp: Slot [19] registered [ 0.288094] acpiphp: Slot [20] registered [ 0.289106] acpiphp: Slot [21] registered [ 0.291108] acpiphp: Slot [22] registered [ 0.292133] acpiphp: Slot [23] registered [ 0.294094] acpiphp: Slot [24] registered [ 0.295098] acpiphp: Slot [25] registered [ 0.297096] acpiphp: Slot [26] registered [ 0.298095] acpiphp: Slot [27] registered [ 0.300093] acpiphp: Slot [28] registered [ 0.301111] acpiphp: Slot [29] registered [ 0.302116] acpiphp: Slot [30] registered [ 0.304134] acpiphp: Slot [31] registered [ 0.306066] PCI host bridge to bus 0000:00 [ 0.307018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.309024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.311023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.312031] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.314021] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.316037] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.318185] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.320992] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.324320] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.332511] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.336054] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.339019] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.341017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.344022] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.346396] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.349819] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.352044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.355819] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.360012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.369780] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.374015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.379651] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.392020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.397013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.418022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.429292] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.440028] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.455024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.495019] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.506860] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.509667] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.512363] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.514391] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.517234] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.522114] iommu: Default domain type: Passthrough [ 0.524419] SCSI subsystem initialized [ 0.526174] ACPI: bus type USB registered [ 0.528094] usbcore: registered new interface driver usbfs [ 0.530083] usbcore: registered new interface driver hub [ 0.532106] usbcore: registered new device driver usb [ 0.534205] pps_core: LinuxPPS API ver. 1 registered [ 0.536014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.539064] PTP clock support registered [ 0.542061] EDAC MC: Ver: 3.0.0 [ 0.544142] PCI: Using ACPI for IRQ routing [ 0.546926] NetLabel: Initializing [ 0.548012] NetLabel: domain hash size = 128 [ 0.550013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.552125] NetLabel: unlabeled traffic allowed by default [ 0.554153] vgaarb: loaded [ 0.556285] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.557011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.561489] clocksource: Switched to clocksource kvm-clock [ 0.675818] VFS: Disk quotas dquot_6.6.0 [ 0.677681] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.680520] *** VALIDATE ramfs *** [ 0.681866] *** VALIDATE hugetlbfs *** [ 0.684171] pnp: PnP ACPI init [ 0.686716] pnp: PnP ACPI: found 6 devices [ 0.715522] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.719045] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.721588] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.724018] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.726878] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.729553] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.732683] NET: Registered protocol family 2 [ 0.735478] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.741258] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.745279] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.752184] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.755793] TCP: Hash tables configured (established 65536 bind 65536) [ 0.759523] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.763110] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.766571] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.769499] NET: Registered protocol family 1 [ 0.771853] RPC: Registered named UNIX socket transport module. [ 0.773150] RPC: Registered udp transport module. [ 0.774535] RPC: Registered tcp transport module. [ 0.775621] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.776991] NET: Registered protocol family 44 [ 0.778149] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.779431] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.780753] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.782247] PCI: CLS 0 bytes, default 64 [ 0.783202] Unpacking initramfs... [ 2.297427] debug: unmapping init [mem 0xffff8a51fcc64000-0xffff8a51fffcffff] [ 2.306618] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.312242] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.316780] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.243048] Initialise system trusted keyrings [ 4.245831] Key type blacklist registered [ 4.248632] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.271541] zbud: loaded [ 4.278808] *** VALIDATE nfs *** [ 4.281156] *** VALIDATE nfs4 *** [ 4.284415] pstore: using deflate compression [ 4.292741] Platform Keyring initialized [ 4.759920] NET: Registered protocol family 38 [ 4.765718] Key type asymmetric registered [ 4.767987] Asymmetric key parser 'x509' registered [ 4.770895] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.775207] io scheduler mq-deadline registered [ 4.777675] io scheduler kyber registered [ 4.780749] io scheduler bfq registered [ 4.784388] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.789501] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.795476] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.809675] ACPI: Power Button [PWRF] [ 4.841872] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.854985] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.876639] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.921319] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.969355] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.000834] Non-volatile memory driver v1.3 [ 5.005935] Linux agpgart interface v0.103 [ 5.117499] virtio_blk virtio1: [vda] 68040 512-byte logical blocks (34.8 MB/33.2 MiB) [ 5.124672] vda: detected capacity change from 0 to 34836480 [ 5.178875] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.185960] vdb: detected capacity change from 0 to 1073741824 [ 5.206656] libphy: Fixed MDIO Bus: probed [ 5.241652] usbcore: registered new interface driver usbserial_generic [ 5.244035] usbserial: USB Serial support registered for generic [ 5.248501] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.260282] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.269764] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.276867] mousedev: PS/2 mouse device common for all mice [ 5.280652] rtc_cmos 00:05: RTC can wake from S4 [ 5.287055] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.293811] rtc_cmos 00:05: registered as rtc0 [ 5.293915] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.293974] intel_pstate: CPU model not supported [ 5.307684] hid: raw HID events driver (C) Jiri Kosina [ 5.307853] usbcore: registered new interface driver usbhid [ 5.307857] usbhid: USB HID core driver [ 5.309248] drop_monitor: Initializing network drop monitor service [ 5.326598] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.329847] Initializing XFRM netlink socket [ 5.341313] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.352407] NET: Registered protocol family 10 [ 5.354318] Segment Routing with IPv6 [ 5.354361] NET: Registered protocol family 17 [ 5.360326] mpls_gso: MPLS GSO support [ 5.430498] RAS: Correctable Errors collector initialized. [ 5.434495] AVX version of gcm_enc/dec engaged. [ 5.438150] AES CTR mode by8 optimization enabled [ 5.729343] sched_clock: Marking stable (5729325095, 0)->(6647720381, -918395286) [ 5.738733] registered taskstats version 1 [ 5.742372] Loading compiled-in X.509 certificates [ 5.744574] zswap: loaded using pool lzo/zbud [ 5.850350] Key type big_key registered [ 5.893911] Key type encrypted registered [ 5.896047] ima: No TPM chip found, activating TPM-bypass! [ 5.898242] ima: Allocated hash algorithm: sha1 [ 5.904449] ima: No architecture policies found [ 5.913380] evm: Initialising EVM extended attributes: [ 5.921306] evm: security.selinux [ 5.924317] evm: security.ima [ 5.928745] evm: security.capability [ 5.934463] evm: HMAC attrs: 0x1 [ 5.951743] rtc_cmos 00:05: setting system clock to 2026-04-14 18:23:38 UTC (1776191018) [ 5.963533] debug: unmapping init [mem 0xffffffff8aa03000-0xffffffff8abfffff] [ 5.972431] debug: unmapping init [mem 0xffffffff89782000-0xffffffff89a58fff] [ 5.979678] Write protecting the kernel read-only data: 28672k [ 5.989906] debug: unmapping init [mem 0xffffffff87e03000-0xffffffff87ffffff] [ 5.993332] debug: unmapping init [mem 0xffffffff88714000-0xffffffff887fffff] [ 6.108605] 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) [ 6.126735] systemd[1]: Detected virtualization kvm. [ 6.130398] systemd[1]: Detected architecture x86-64. [ 6.136685] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 6.212251] systemd[1]: No hostname configured. [ 6.215322] systemd[1]: Set hostname to . [ 6.217391] random: systemd: uninitialized urandom read (16 bytes read) [ 6.224869] systemd[1]: Initializing machine ID from random generator. [ 6.370803] random: ln: uninitialized urandom read (6 bytes read) [ 6.610745] random: systemd: uninitialized urandom read (16 bytes read) [ 6.614593] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 6.621207] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 6.626654] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. [ OK ] Reached target Slices. Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Reached target Initrd Root Device. Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.340959] device-mapper: uevent: version 1.0.3 [ 8.344792] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 10.476194] virtio_net virtio0 ens2: renamed from eth0 [ 10.528435] random: fast init done [ 10.732955] scsi host0: ata_piix [ 10.922776] scsi host1: ata_piix [ 10.944252] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.954610] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.468547] random: crng init done [ 15.470894] random: 7 urandom warning(s) missed due to ratelimiting [ 18.566110] dracut-initqueue[605]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 20.542584] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ 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 Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 24.440256] printk: systemd: 26 output lines suppressed due to ratelimiting [ 25.279854] SELinux: Disabled at runtime. [ 25.475255] 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) [ 25.508470] systemd[1]: Detected virtualization kvm. [ 25.518074] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.676568] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.686136] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 26.702710] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 26.719199] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 26.725105] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.735430] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.746171] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target Paths. Mounting Huge Pages File System... Mounting Kernel Debug File System... [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables...[ 27.297225] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ 27.570014] hrtimer: interrupt took 4021121 ns [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... Starting Create Static Device Nodes in /dev... [ 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. [ 28.413248] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 29.297857] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.370262] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 30.057456] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 30.261411] EDAC sbridge: Ver: 1.1.2 [ 33.774913] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit)[ 34.896300] NFS: Registering the id_resolver key type [ 34.899092] Key type id_resolver registered [ 34.901605] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (8s / no limit) [ *** ] A start job is running for Configur…-only root support (8s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg212-client login: [ 104.244842] libcfs: loading out-of-tree module taints kernel. [ 104.486044] alg: No test for adler32 (adler32-zlib) [ 105.255400] Key type ._llcrypt registered [ 105.260273] Key type .llcrypt registered [ 106.056819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 107.217870] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [ 109.004747] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [ 109.017861] LNet: Accept secure, port 988 [ 111.055387] Key type lgssc registered [ 113.807429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 292.923574] Lustre: Mounted lustre-client [ 299.221312] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 314.356370] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing check_logdir /tmp/testlogs/ [ 318.433816] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 22s idle [ 318.687232] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing yml_node [ 324.036296] Lustre: DEBUG MARKER: Client: 2.15.8.10 [ 326.174456] Lustre: DEBUG MARKER: MDS: 2.15.8.10 [ 328.661798] Lustre: DEBUG MARKER: OSS: 2.15.8.10 [ 330.224677] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Apr 14 14:29:01 EDT 2026 [ 338.523026] Lustre: DEBUG MARKER: excepting tests: 27 28 [ 340.272538] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 341.232748] Lustre: Mounted lustre-client [ 348.485304] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing check_config_client /mnt/lustre [ 370.374489] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 382.223047] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 14:29:53 (1776191393) [ 388.696788] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 14:29:59 (1776191399) [ 395.201383] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 14:30:06 (1776191406) [ 402.169311] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 14:30:13 (1776191413) [ 410.204568] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 14:30:21 (1776191421) [ 418.387993] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 14:30:29 (1776191429) [ 425.778432] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 14:30:37 (1776191437) [ 433.131082] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 14:30:44 (1776191444) [ 440.280712] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 14:30:51 (1776191451) [ 447.935432] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 14:30:58 (1776191458) [ 454.116130] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 20s idle [ 454.121350] Lustre: Skipped 1 previous similar message [ 456.135995] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 14:31:07 (1776191467) [ 462.703304] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 14:31:13 (1776191473) [ 469.472526] Lustre: lustre-OST0000-osc-ffff8a5250341000: disconnect after 21s idle [ 471.588697] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 14:31:22 (1776191482) [ 479.937209] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 14:31:30 (1776191490) [ 486.989853] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 14:31:38 (1776191498) [ 489.951895] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 21s idle [ 492.844666] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 14:31:44 (1776191504) [ 499.703398] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 14:31:50 (1776191510) [ 506.889734] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 14:31:57 (1776191517) [ 514.541403] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 14:32:05 (1776191525) [ 523.092946] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 14:32:14 (1776191534) [ 523.716563] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279507 file: /mnt/lustre/lockdir/lockfile=144115205289279506 [ 660.046593] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 14:34:31 (1776191671) [ 668.970153] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 14:34:39 (1776191679) [ 675.652657] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 14:34:47 (1776191687) [ 682.706299] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 14:34:53 (1776191693) [ 689.820956] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 14:35:01 (1776191701) [ 697.087901] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 14:35:08 (1776191708) [ 699.184186] Lustre: DEBUG MARKER: chmod [ 706.458861] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 14:35:17 (1776191717) [ 714.478688] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7207524kB free gt MAXFREE 800000kB, increase 800000 (or reduce test fs size) to proceed [ 725.277412] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 14:35:36 (1776191736) [ 1002.138652] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 14:40:13 (1776192013) [ 1126.344454] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 14:42:17 (1776192137) [ 1316.355686] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 14:45:27 (1776192327) [ 1355.240236] Lustre: lustre-OST0001-osc-ffff8a5250341000: disconnect after 20s idle [ 1356.722325] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 14:46:07 (1776192367) [ 1367.265894] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 14:46:17 (1776192377) [ 1368.202071] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.302709] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.370459] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.429988] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.486550] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.571896] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.645091] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.720528] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.794898] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.852031] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.915280] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1368.975947] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.049110] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.127260] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.204621] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.320785] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.392085] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.426943] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.467190] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.508943] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.548348] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.583920] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.616215] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.649990] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.686248] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.722473] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.764708] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.838906] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.925896] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1369.967988] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.013863] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.088938] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.128564] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.171161] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.221069] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.276047] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.317576] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.367639] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.413428] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.466237] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.495833] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.547095] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.590606] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.628625] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.673701] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.724616] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.763640] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.832683] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.902534] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1370.959564] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.012190] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.137655] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.202252] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.264536] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.328361] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.389152] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.453665] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.504238] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.549169] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.624230] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.663883] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.696174] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.764698] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.809307] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.852890] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.883329] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.938510] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1371.969141] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.007971] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.046891] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.096748] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.126222] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.161853] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.223433] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.282585] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.326396] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.363252] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.403541] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.443950] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.514656] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.566597] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.609762] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.700509] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.791051] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.833803] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.876926] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.926539] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.950976] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1372.996779] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.050180] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.088028] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.177025] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.232296] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.264780] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.300428] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.355396] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.395837] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.426972] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.468997] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.512978] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.546542] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.602595] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.674654] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.743039] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.776857] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.812972] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.861436] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.899129] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.933162] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1373.974375] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.017666] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.072845] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.110744] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.152553] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.201517] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.259746] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.318766] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.369736] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.450225] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.545624] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.595874] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.648130] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.723040] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.801934] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.878564] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1374.977672] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.106066] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.208142] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.309301] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.394632] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.502931] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.624369] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.715397] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.838697] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1375.948949] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.059468] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.137990] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.248585] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.393126] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.525586] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.636088] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.769895] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.904866] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1376.958509] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.026541] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.088314] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.161826] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.348989] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.491674] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.578978] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.665755] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.774190] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.828233] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.878940] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.921120] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.953391] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1377.997712] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.058951] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.122340] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.207485] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.275780] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.336754] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.419832] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.488621] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.550917] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.665953] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.744456] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.811992] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.858285] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.937403] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1378.996543] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.089311] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.232648] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.314937] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.617545] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.774105] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.919818] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1379.977879] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.035885] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.095573] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.133880] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.188507] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.226741] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.280988] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.330610] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.387403] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.466133] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.545553] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.640600] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.681864] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.737788] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.779215] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.829654] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.834134] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 22s idle [ 1380.870212] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1380.921683] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.001689] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.067892] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.155900] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.233125] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.316586] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.434243] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.518370] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.606773] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.702930] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.770384] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.838672] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.902755] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1381.982885] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.078879] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.148480] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.249244] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.333789] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.405870] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.523348] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.631856] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.761128] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.875307] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1382.984562] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.061515] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.144706] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.232863] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.320802] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.397371] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.473346] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.574316] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.655827] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.745817] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.824632] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1383.930951] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.000721] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.059058] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.100095] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.142410] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.175910] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.217682] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.261521] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.299202] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.364964] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.401829] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.462886] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.549415] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.619856] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.699103] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.783084] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.848964] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1384.917726] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.049936] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.156790] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.278189] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.373119] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.522678] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.630657] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.689073] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.763325] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.812300] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.848489] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.888350] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.938968] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1385.985403] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.035402] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.073773] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.123527] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.172454] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.238568] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.297154] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.349941] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.390549] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.444109] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.531078] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.644291] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.740231] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.771139] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.851581] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1386.917163] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.006773] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.089857] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.193193] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.270340] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.346515] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1387.412976] rw_seq_cst_vs_d (29864): drop_caches: 3 [ 1394.468810] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 14:46:45 (1776192405) [ 1394.897742] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.098078] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.182800] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.367148] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.433944] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.629638] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.768559] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.834626] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.911942] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1395.977685] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.076605] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.275781] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.352825] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.464645] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.674745] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.744520] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.783833] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1396.906344] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.002824] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.172092] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.282185] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.368247] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.511825] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.647623] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.786635] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1397.913804] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.121673] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.261437] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.543730] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.667530] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.770760] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.806648] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.904455] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1398.967806] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.156991] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.258993] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.413408] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.512960] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.576668] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.630919] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.675177] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.807875] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.851901] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.901515] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1399.945381] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.041322] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.128746] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.321785] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.513136] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.587145] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.624542] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.834362] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.887395] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1400.947725] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.109876] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.266962] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.319246] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.651347] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.779732] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1401.891441] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.102218] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.173211] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.276149] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.409625] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.547966] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.721303] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.765462] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.843185] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.914108] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1402.992532] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.054527] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.190267] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.245961] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.359361] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.404648] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.502403] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.558565] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.628348] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.711228] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.779678] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.848274] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.896694] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1403.977218] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.051892] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.106665] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.263549] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.321880] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.366338] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.456133] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.503205] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.648709] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.831210] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1404.944164] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.097726] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.326912] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.465845] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.624348] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.757434] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1405.983690] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.067993] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.148741] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.392605] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.512487] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.765715] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.863220] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1406.992245] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.051513] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.088062] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.188406] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.238753] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.309439] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.481048] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.592215] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.657501] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.857932] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1407.996908] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.117417] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.192202] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.246831] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.361405] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.493205] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.620706] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.737533] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.839800] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1408.968441] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.016571] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.109631] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.295917] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.373503] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.480300] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.787610] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.827634] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.903445] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1409.990825] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.123083] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.310853] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.548996] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.698583] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.940159] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1410.997378] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.068513] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.235521] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.382368] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.507665] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.553136] Lustre: lustre-OST0001-osc-ffff8a5250341000: disconnect after 25s idle [ 1411.559560] Lustre: Skipped 1 previous similar message [ 1411.632207] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1411.733453] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1412.129096] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1412.237864] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1412.628129] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1412.778473] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1412.935424] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.082297] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.172280] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.280690] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.327821] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.449271] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.593167] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.810151] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.875579] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1413.926423] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1414.174578] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1414.348101] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1414.444843] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1414.532121] rw_seq_cst_vs_d (30451): drop_caches: 3 [ 1416.671775] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 24s idle [ 1421.653784] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 14:47:12 (1776192432) [ 1428.754432] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 14:47:19 (1776192439) [ 1438.869784] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 14:47:30 (1776192450) [ 1458.851789] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 14:47:50 (1776192470) [ 1467.386708] Lustre: DEBUG MARKER: loop 5 [ 1472.260288] Lustre: DEBUG MARKER: loop 10 [ 1472.991986] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 22s idle [ 1477.385512] Lustre: DEBUG MARKER: loop 15 [ 1482.897952] Lustre: DEBUG MARKER: loop 20 [ 1490.659800] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 14:48:21 (1776192501) [ 1497.230213] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 14:48:28 (1776192508) [ 1504.437624] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 14:48:35 (1776192515) [ 1513.951457] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 23s idle [ 1513.959313] Lustre: Skipped 1 previous similar message [ 1573.684980] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 14:49:44 (1776192584) [ 1580.528566] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 14:49:51 (1776192591) [ 1586.473140] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 14:49:58 (1776192598) [ 1593.096290] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 14:50:04 (1776192604) [ 1599.640567] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 14:50:11 (1776192611) [ 1606.154681] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 14:50:17 (1776192617) [ 1614.091673] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1615.223644] Lustre: DEBUG MARKER: SKIP: sanityn test_28 skipping ALWAYS excluded test 28 [ 1617.156151] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 14:50:28 (1776192628) [ 1627.067080] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 14:50:37 (1776192637) [ 1627.378916] Lustre: *** cfs_fail_loc=314, val=0*** [ 1628.447340] Lustre: *** cfs_fail_loc=314, val=0*** [ 1628.453308] Lustre: Skipped 2 previous similar messages [ 1635.815677] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 14:50:46 (1776192646) [ 1641.955249] Lustre: lustre-OST0001-osc-ffff8a5250341000: disconnect after 23s idle [ 1641.965700] Lustre: Skipped 3 previous similar messages [ 1645.392696] Lustre: *** cfs_fail_loc=314, val=0*** [ 1645.486187] LustreError: 11-0: lustre-OST0000-osc-ffff8a5250341000: operation ldlm_enqueue to node 192.168.202.112@tcp failed: rc = -107 [ 1645.498791] Lustre: lustre-OST0000-osc-ffff8a5250341000: Connection to lustre-OST0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1645.545962] LustreError: lustre-OST0000-osc-ffff8a5250341000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1645.578268] LustreError: 41267:0:(ldlm_resource.c:1127:ldlm_resource_complain()) lustre-OST0000-osc-ffff8a5250341000: namespace resource [0x280000400:0x6:0x0].0x0 (00000000d4dfc0b3) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1645.609664] Lustre: lustre-OST0000-osc-ffff8a5250341000: Connection restored to (at 192.168.202.112@tcp) [ 1653.125456] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 14:51:04 (1776192664) [ 1653.729846] LustreError: 41854:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1656.759485] LustreError: 41854:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1419 awake [ 1663.789795] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1665.550990] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 14:51:16 (1776192676) [ 1667.837978] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1670.473863] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-Lock-Cancel ========================================================== 14:51:20 (1776192680) [ 1677.799979] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 22s idle [ 1677.818498] Lustre: Skipped 1 previous similar message [ 1677.848992] Lustre: lustre-MDT0000-mdc-ffff8a52584ac000: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1682.915446] LustreError: 166-1: MGC192.168.202.112@tcp: Connection to MGS (at 192.168.202.112@tcp) was lost; in progress operations using this service will fail [ 1682.954892] Lustre: Evicted from MGS (at 192.168.202.112@tcp) after server handle changed from 0x168fccd71fb35156 to 0x168fccd71fbc904b [ 1682.991661] Lustre: MGC192.168.202.112@tcp: Connection restored to (at 192.168.202.112@tcp) [ 1691.943549] Lustre: lustre-MDT0000-mdc-ffff8a52584ac000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 1713.243174] Lustre: DEBUG MARKER: == sanityn test 33d: DNE distributed operation should trigger COS ========================================================== 14:52:04 (1776192724) [ 1830.368078] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 23s idle [ 1830.383155] Lustre: Skipped 1 previous similar message [ 1841.467798] Lustre: DEBUG MARKER: == sanityn test 33e: DNE local operation shouldn't trigger COS ========================================================== 14:54:12 (1776192852) [ 1843.147393] Lustre: DEBUG MARKER: SKIP: sanityn test_33e Need two or more clients, have 1 [ 1845.284867] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 14:54:16 (1776192856) [ 1895.880554] Lustre: lustre-OST0001-osc-ffff8a5250341000: Connection to lustre-OST0001 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1895.901472] Lustre: Skipped 1 previous similar message [ 1895.911672] LustreError: lustre-OST0001-osc-ffff8a52584ac000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1895.926540] Lustre: lustre-OST0001-osc-ffff8a52584ac000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 1895.926928] LustreError: lustre-OST0001-osc-ffff8a5250341000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1895.935793] Lustre: Skipped 1 previous similar message [ 1902.063126] Lustre: lustre-OST0000-osc-ffff8a52584ac000: Connection to lustre-OST0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1902.078749] Lustre: Skipped 1 previous similar message [ 1902.095959] LustreError: lustre-OST0000-osc-ffff8a52584ac000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1902.123716] Lustre: lustre-OST0000-osc-ffff8a52584ac000: Connection restored to 192.168.202.112@tcp (at 192.168.202.112@tcp) [ 1902.132150] Lustre: Skipped 1 previous similar message [ 1922.983604] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1924.524610] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid in FULL state after 0 sec [ 1925.894399] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1932.921502] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1934.518911] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid in IDLE state after 0 sec [ 1936.095945] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1942.952648] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1944.540863] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid in FULL state after 0 sec [ 1946.470315] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1953.984570] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1955.914579] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid in IDLE state after 0 sec [ 1957.893866] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1971.869126] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1973.348850] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a5250341000.ost_server_uuid in FULL state after 0 sec [ 1975.063462] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1981.047385] Lustre: DEBUG MARKER: oleg212-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid,osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid 40 [ 1982.822194] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a5250341000.ost_server_uuid in IDLE state after 0 sec [ 1984.417815] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a52584ac000.ost_server_uuid in IDLE state after 0 sec [ 1986.589528] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 14:56:37 (1776192997) [ 1989.944266] Lustre: DEBUG MARKER: Race attempt 0 [ 1993.473360] Lustre: DEBUG MARKER: Wait for 54379 54433 for 60 sec... [ 2061.183167] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 14:57:52 (1776193072) [ 2070.298832] Lustre: DEBUG MARKER: start test - cycle (0) [ 2094.617865] Lustre: DEBUG MARKER: start test - cycle (1) [ 2121.089314] Lustre: DEBUG MARKER: start test - cycle (2) [ 2127.338620] Lustre: lustre-OST0001-osc-ffff8a5250341000: disconnect after 21s idle [ 2127.346794] Lustre: Skipped 3 previous similar messages [ 2146.782703] Lustre: DEBUG MARKER: start test - cycle (3) [ 2172.982085] Lustre: DEBUG MARKER: start test - cycle (4) [ 2197.919839] Lustre: DEBUG MARKER: start test - cycle (5) [ 2222.671667] Lustre: DEBUG MARKER: start test - cycle (6) [ 2247.485336] Lustre: DEBUG MARKER: start test - cycle (7) [ 2273.386373] Lustre: DEBUG MARKER: start test - cycle (8) [ 2298.726810] Lustre: DEBUG MARKER: start test - cycle (9) [ 2326.065606] Lustre: DEBUG MARKER: start test - cycle (10) [ 2361.716465] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 15:02:52 (1776193372) [ 2442.233608] Lustre: DEBUG MARKER: == sanityn test 39a: test from 11063 ============================================================================================ 15:04:13 (1776193453) [ 2449.461302] Lustre: DEBUG MARKER: == sanityn test 39b: 11063 problem 1 ============================================================================================ 15:04:20 (1776193460) [ 2457.401583] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 15:04:28 (1776193468) [ 2465.249294] Lustre: lustre-OST0000-osc-ffff8a5250341000: disconnect after 22s idle [ 2465.255758] Lustre: Skipped 6 previous similar messages [ 2466.771734] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 15:04:37 (1776193477) [ 2467.090079] Lustre: *** cfs_fail_loc=411, val=0*** [ 2474.614797] Lustre: DEBUG MARKER: == sanityn test 40a: pdirops: create vs others ======================================================================== 15:04:45 (1776193485) [ 2493.673152] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 15:05:04 (1776193504) [ 2511.588516] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 15:05:22 (1776193522) [ 2530.517152] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 15:05:41 (1776193541) [ 2548.425683] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 15:05:59 (1776193559) [ 2564.617970] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 15:06:15 (1776193575) [ 2578.929867] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 15:06:29 (1776193589) [ 2595.747404] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 15:06:46 (1776193606) [ 2611.094364] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 15:07:02 (1776193622) [ 2624.953747] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 15:07:16 (1776193636) [ 2639.462063] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 15:07:30 (1776193650) [ 2655.948639] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 15:07:46 (1776193666) [ 2671.313814] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 15:08:02 (1776193682) [ 2686.842959] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 15:08:17 (1776193697) [ 3826.533357] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 15:27:17 (1776194837) [ 3838.976269] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 15:27:30 (1776194850) [ 3853.989405] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 15:27:44 (1776194864) [ 3869.735109] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 15:28:00 (1776194880) [ 3883.488757] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 21s idle [ 3883.495223] Lustre: Skipped 12 previous similar messages [ 3883.953965] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 15:28:15 (1776194895) [ 3897.122908] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 15:28:28 (1776194908) [ 3912.610879] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 15:28:43 (1776194923) [ 3928.725183] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 15:28:59 (1776194939) [ 3942.784855] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 15:29:14 (1776194954) [ 4024.502843] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 15:30:35 (1776195035) [ 4038.495605] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 15:30:49 (1776195049) [ 4052.814754] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 15:31:03 (1776195063) [ 4067.054086] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 15:31:18 (1776195078) [ 4067.807251] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 21s idle [ 4067.816274] Lustre: Skipped 1 previous similar message [ 4081.761432] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 15:31:32 (1776195092) [ 4096.989884] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 15:31:47 (1776195107) [ 4111.821211] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 15:32:02 (1776195122) [ 4127.253712] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 15:32:18 (1776195138) [ 4143.462576] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 15:32:34 (1776195154) [ 4303.731330] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 15:35:14 (1776195314) [ 4431.327221] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 23s idle [ 4431.334941] Lustre: Skipped 5 previous similar messages [ 4728.287288] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 24s idle [ 4728.289819] Lustre: Skipped 4 previous similar messages [ 5654.788627] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 15:57:45 (1776196665) [ 5668.292609] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 15:57:59 (1776196679) [ 5670.368240] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 20s idle [ 5670.376059] Lustre: Skipped 5 previous similar messages [ 5682.324629] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 15:58:13 (1776196693) [ 5697.800044] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 15:58:28 (1776196708) [ 5713.776699] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 15:58:44 (1776196724) [ 5734.453906] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 15:59:04 (1776196744) [ 5751.926433] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 15:59:22 (1776196762) [ 5767.918574] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 15:59:38 (1776196778) [ 5783.822205] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 15:59:54 (1776196794) [ 5800.283175] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 16:00:11 (1776196811) [ 5923.577963] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 16:02:14 (1776196934) [ 5939.570241] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 16:02:30 (1776196950) [ 5954.825376] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 16:02:45 (1776196965) [ 5971.232272] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 16:03:01 (1776196981) [ 5987.107129] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 16:03:18 (1776196998) [ 6002.741533] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 16:03:33 (1776197013) [ 6018.358403] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 16:03:48 (1776197028) [ 6034.073119] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 16:04:05 (1776197045) [ 6047.134810] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 16:04:18 (1776197058) [ 6387.167493] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 20s idle [ 6387.169961] Lustre: Skipped 11 previous similar messages [ 7006.687920] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 22s idle [ 7006.696595] Lustre: Skipped 12 previous similar messages [ 7389.109980] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 16:26:40 (1776198400) [ 7401.204982] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 16:26:52 (1776198412) [ 7414.105221] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 16:27:05 (1776198425) [ 7426.582110] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 16:27:17 (1776198437) [ 7437.901648] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 16:27:29 (1776198449) [ 7448.626461] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 16:27:40 (1776198460) [ 7462.527899] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 16:27:53 (1776198473) [ 7475.440304] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 16:28:06 (1776198486) [ 7489.595478] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 16:28:20 (1776198500) [ 7506.080470] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 16:28:37 (1776198517) [ 7519.217759] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 16:28:50 (1776198530) [ 7532.869810] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 16:29:04 (1776198544) [ 7545.328492] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 16:29:16 (1776198556) [ 7559.718695] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 16:29:30 (1776198570) [ 7573.906653] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 16:29:45 (1776198585) [ 7587.021228] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 16:29:58 (1776198598) [ 7601.775376] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 16:30:13 (1776198613) [ 7602.019086] LustreError: 5597:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7604.103648] LustreError: 5597:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 410 awake [ 7612.984746] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 16:30:24 (1776198624) [ 7621.041583] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 16:30:32 (1776198632) [ 7621.331292] LustreError: 267724:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7625.392199] LustreError: 267724:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 7625.434678] LustreError: 267724:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7629.495705] LustreError: 267724:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 7629.567793] LustreError: 267730:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7633.647490] LustreError: 267730:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 1404 awake [ 7639.594994] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 16:30:51 (1776198651) [ 7650.728787] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 16:31:02 (1776198662) [ 7657.644621] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 16:31:09 (1776198669) [ 7662.049137] Lustre: lustre-OST0000-osc-ffff8a52584ac000: disconnect after 22s idle [ 7662.052033] Lustre: Skipped 10 previous similar messages [ 7666.534781] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 16:31:17 (1776198677) [ 7699.770441] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 16:31:50 (1776198710) [ 7712.613531] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 16:32:03 (1776198723) [ 7724.386444] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 16:32:15 (1776198735) [ 7742.209960] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 16:32:33 (1776198753) [ 7756.796580] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 16:32:48 (1776198768) [ 7762.468686] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7767.922706] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 16:32:59 (1776198779) [ 7774.090846] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 16:33:05 (1776198785) [ 7780.164410] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 16:33:11 (1776198791) [ 7785.777690] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 16:33:17 (1776198797) [ 7810.387934] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 16:33:41 (1776198821) [ 7861.667942] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 16:34:32 (1776198872) [ 7867.518903] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 16:34:38 (1776198878) [ 7873.144950] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 16:34:44 (1776198884) [ 7876.402364] LustreError: 11-0: lustre-MDT0000-mdc-ffff8a5250341000: operation ldlm_enqueue to node 192.168.202.112@tcp failed: rc = -35 [ 7883.181994] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 16:34:54 (1776198894) [ 7883.886980] LustreError: 2286:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7885.975380] LustreError: 2286:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 31d awake [ 7894.485422] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 16:35:05 (1776198905) [ 8036.536597] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 16:37:27 (1776199047) [ 8045.427583] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 16:37:36 (1776199056) [ 8057.454427] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 16:37:48 (1776199068) [ 8075.041830] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 16:38:06 (1776199086) [ 8092.656413] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 16:38:23 (1776199103) [ 8119.784543] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 16:38:50 (1776199130) [ 8145.344603] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 16:39:16 (1776199156) [ 8155.529224] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 16:39:26 (1776199166) [ 8167.861161] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 16:39:38 (1776199178) [ 8190.046892] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 16:40:01 (1776199201) [ 8242.664955] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 16:40:53 (1776199253) [ 8266.207274] Lustre: lustre-OST0001-osc-ffff8a52584ac000: disconnect after 20s idle [ 8266.221593] Lustre: Skipped 8 previous similar messages [ 8372.056170] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with NID/JobID/OPCode expression ========================================================== 16:43:03 (1776199383) [ 8706.140870] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 16:48:37 (1776199717) [ 8715.911522] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 16:48:47 (1776199727) [ 8770.955934] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 16:49:42 (1776199782) [ 8836.635369] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 16:50:47 (1776199847) [ 8848.343385] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 16:50:59 (1776199859) [ 8963.302217] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 16:52:54 (1776199974) [ 8996.823993] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 16:53:28 (1776200008) [ 9003.926854] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 16:53:35 (1776200015) [ 9020.953530] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 16:53:52 (1776200032) [ 9022.653111] LustreError: 307447:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x200000402:0x7b4:0x0] mdc close failed: rc = -116 [ 9023.815678] LustreError: 307450:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x240000402:0x600:0x0] mdc close failed: rc = -116 [ 9023.825385] LustreError: 307450:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 9029.359816] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 16:54:00 (1776200040) [ 9032.771087] LustreError: 308085:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x200000402:0x7bd:0x0] mdc close failed: rc = -116 [ 9032.777283] LustreError: 308085:0:(file.c:246:ll_close_inode_openhandle()) Skipped 9 previous similar messages [ 9038.764913] LustreError: 308182:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a5250341000: inode [0x240000402:0x652:0x0] mdc close failed: rc = -2 [ 9046.650558] LustreError: 308303:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x240000402:0x664:0x0] mdc close failed: rc = -116 [ 9046.661486] LustreError: 308303:0:(file.c:246:ll_close_inode_openhandle()) Skipped 2 previous similar messages [ 9056.460607] LustreError: 308448:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x240000402:0x687:0x0] mdc close failed: rc = -116 [ 9056.474285] LustreError: 308448:0:(file.c:246:ll_close_inode_openhandle()) Skipped 1 previous similar message [ 9074.826948] LustreError: 308662:0:(file.c:246:ll_close_inode_openhandle()) lustre-clilmv-ffff8a52584ac000: inode [0x200000402:0x872:0x0] mdc close failed: rc = -116 [ 9074.840603] LustreError: 308662:0:(file.c:246:ll_close_inode_openhandle()) Skipped 4 previous similar messages [ 9096.159734] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 16:55:07 (1776200107) [ 9102.271855] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 16:55:13 (1776200113) [ 9111.011525] Lustre: lustre-OST0000-osc-ffff8a5250341000: disconnect after 21s idle [ 9111.026940] Lustre: Skipped 13 previous similar messages [ 9178.530024] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 16:56:29 (1776200189) [ 9179.750503] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9181.326624] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 16:56:32 (1776200192) [ 9186.884636] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 16:56:38 (1776200198) [ 9313.730906] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 16:58:44 (1776200324) [ 9326.423264] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 16:58:57 (1776200337) [ 9512.845458] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 17:02:04 (1776200524) [ 9698.882992] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 17:05:10 (1776200710) [ 9706.638441] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 17:05:17 (1776200717) [ 9724.126997] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 17:05:34 (1776200734) [ 9724.363734] Lustre: DEBUG MARKER: write [ 9724.396480] LustreError: 5607:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 sleeping for 5000ms [ 9726.424577] Lustre: DEBUG MARKER: kill 321999 [ 9726.430772] LustreError: 321999:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 sleeping for 6000ms [ 9729.399598] LustreError: 5607:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 312 awake [ 9732.511090] LustreError: 321999:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 329 awake [ 9738.366948] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 17:05:49 (1776200749) [ 9738.956751] LustreError: 322598:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 9741.047308] LustreError: 322598:0:(fail.c:144:__cfs_fail_timeout_set()) cfs_fail_timeout interrupted [ 9750.762933] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 17:06:02 (1776200762) [ 9752.215625] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 9753.878679] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 17:06:05 (1776200765) [ 9760.510845] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 17:06:11 (1776200771) [ 9767.136546] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 17:06:18 (1776200778) [ 9774.218872] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 17:06:25 (1776200785) [ 9780.535822] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 17:06:31 (1776200791) [ 9787.629213] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 17:06:38 (1776200798) [ 9793.985974] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 17:06:45 (1776200805) [ 9802.079515] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 17:06:53 (1776200813) [ 9810.679328] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 17:07:01 (1776200821) [ 9812.473717] Lustre: *** cfs_fail_loc=415, val=0*** [ 9823.346838] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 17:07:14 (1776200834) [ 9853.835214] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 17:07:45 (1776200865) [ 9854.131984] LustreError: 20998:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9859.232837] LustreError: 20998:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 9864.352243] LustreError: 20998:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 9864.356198] LustreError: 20998:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9864.360968] LustreError: 20998:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 9874.463134] LustreError: 20998:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 9874.482300] LustreError: 20998:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 1 previous similar message [ 9884.623628] LustreError: 295979:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9884.632092] LustreError: 295979:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 9894.767124] LustreError: 295979:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 9894.773251] LustreError: 295979:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 3 previous similar messages [ 9920.015428] LustreError: 13960:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9920.024718] LustreError: 13960:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 9930.135120] LustreError: 13960:0:(fail.c:149:__cfs_fail_timeout_set()) cfs_fail_timeout id 416 awake [ 9930.139783] LustreError: 13960:0:(fail.c:149:__cfs_fail_timeout_set()) Skipped 6 previous similar messages [ 9941.665761] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 17:09:12 (1776200952) [ 9948.234687] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 17:09:19 (1776200959) [ 9955.216774] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 17:09:26 (1776200966) [ 9961.723163] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 17:09:32 (1776200972) [ 9970.472972] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 17:09:41 (1776200981) [ 9984.421982] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 17:09:55 (1776200995) [ 9985.150925] LustreError: 333232:0:(fail.c:138:__cfs_fail_timeout_set()) cfs_fail_timeout id 414 sleeping for 4000ms [ 9985.166336] LustreError: 333232:0:(fail.c:138:__cfs_fail_timeout_set()) Skipped 2 previous similar messages [ 9996.577264] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 17:10:07 (1776201007) [10001.052733] Lustre: Unmounted lustre-client [10003.807935] Lustre: Unmounted lustre-client [10005.155403] Lustre: DEBUG MARKER: Iteration 1 [10005.647838] LustreError: 334114:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10005.654447] LustreError: 334115:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10005.666185] LustreError: 334114:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [10005.958703] Lustre: Mounted lustre-client [10007.770991] Lustre: Unmounted lustre-client [10010.547373] Key type lgssc unregistered [10010.791037] LNet: 334465:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10011.809888] LNet: Removed LNI 192.168.202.12@tcp [10012.755602] Key type .llcrypt unregistered [10012.766558] Key type ._llcrypt unregistered [10013.571349] alg: No test for adler32 (adler32-zlib) [10014.389855] Key type ._llcrypt registered [10014.394778] Key type .llcrypt registered [10014.765931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10015.656079] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10016.744412] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10016.758782] LNet: Accept secure, port 988 [10018.599310] Key type lgssc registered [10020.011981] Lustre: Echo OBD driver; http://www.lustre.org/ [10033.986278] Lustre: DEBUG MARKER: Iteration 2 [10034.526898] LustreError: 335256:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10034.535624] LustreError: 335261:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10034.549604] LustreError: 335256:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [10035.971969] Lustre: Mounted lustre-client [10037.728323] Lustre: Unmounted lustre-client [10041.576928] Key type lgssc unregistered [10041.809656] LNet: 335614:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10042.853934] LNet: Removed LNI 192.168.202.12@tcp [10043.657808] Key type .llcrypt unregistered [10043.659059] Key type ._llcrypt unregistered [10044.391625] alg: No test for adler32 (adler32-zlib) [10045.148478] Key type ._llcrypt registered [10045.150556] Key type .llcrypt registered [10045.355587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10045.684706] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10045.893365] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10045.897605] LNet: Accept secure, port 988 [10047.616766] Key type lgssc registered [10048.499627] Lustre: Echo OBD driver; http://www.lustre.org/ [10058.737149] Lustre: DEBUG MARKER: Iteration 3 [10059.393694] LustreError: 336408:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10059.396166] LustreError: 336409:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10059.434057] LustreError: 336408:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4974 [10060.961318] Lustre: Mounted lustre-client [10060.979766] Lustre: Skipped 1 previous similar message [10062.586731] Lustre: Unmounted lustre-client [10065.644480] Key type lgssc unregistered [10065.872866] LNet: 336761:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10066.912532] LNet: Removed LNI 192.168.202.12@tcp [10067.376209] Key type .llcrypt unregistered [10067.379174] Key type ._llcrypt unregistered [10068.735112] alg: No test for adler32 (adler32-zlib) [10069.582902] Key type ._llcrypt registered [10069.586880] Key type .llcrypt registered [10069.935991] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10070.289046] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10070.521743] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10070.525564] LNet: Accept secure, port 988 [10072.239112] Key type lgssc registered [10073.795787] Lustre: Echo OBD driver; http://www.lustre.org/ [10087.139854] Lustre: DEBUG MARKER: Iteration 4 [10087.448115] LustreError: 337554:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10087.454497] LustreError: 337555:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10087.463417] LustreError: 337554:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [10088.663485] Lustre: Mounted lustre-client [10090.339395] Lustre: Unmounted lustre-client [10092.842587] Key type lgssc unregistered [10093.066219] LNet: 337903:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10094.113176] LNet: Removed LNI 192.168.202.12@tcp [10094.871782] Key type .llcrypt unregistered [10094.877084] Key type ._llcrypt unregistered [10095.734528] alg: No test for adler32 (adler32-zlib) [10096.490828] Key type ._llcrypt registered [10096.495423] Key type .llcrypt registered [10096.679179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10096.892831] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10097.028520] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10097.031611] LNet: Accept secure, port 988 [10098.672340] Key type lgssc registered [10099.673418] Lustre: Echo OBD driver; http://www.lustre.org/ [10110.817969] Lustre: DEBUG MARKER: Iteration 5 [10111.199447] LustreError: 338698:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10111.201179] LustreError: 338699:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10111.225316] LustreError: 338698:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4983 [10112.609089] Lustre: Mounted lustre-client [10112.621794] Lustre: Skipped 1 previous similar message [10114.258178] Lustre: Unmounted lustre-client [10117.148182] Key type lgssc unregistered [10117.382856] LNet: 339051:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10118.438664] LNet: Removed LNI 192.168.202.12@tcp [10119.192617] Key type .llcrypt unregistered [10119.194475] Key type ._llcrypt unregistered [10119.879641] alg: No test for adler32 (adler32-zlib) [10120.655666] Key type ._llcrypt registered [10120.658061] Key type .llcrypt registered [10120.863831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10121.141627] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10121.323021] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10121.327788] LNet: Accept secure, port 988 [10123.015124] Key type lgssc registered [10124.227844] Lustre: Echo OBD driver; http://www.lustre.org/ [10140.648233] Lustre: DEBUG MARKER: Iteration 6 [10141.095726] LustreError: 339847:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10141.099155] LustreError: 339848:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10141.125812] LustreError: 339847:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [10142.527831] Lustre: Mounted lustre-client [10144.876714] Lustre: Unmounted lustre-client [10147.916605] Key type lgssc unregistered [10148.105374] LNet: 340200:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10149.151733] LNet: Removed LNI 192.168.202.12@tcp [10149.935608] Key type .llcrypt unregistered [10149.938801] Key type ._llcrypt unregistered [10150.838801] alg: No test for adler32 (adler32-zlib) [10151.639212] Key type ._llcrypt registered [10151.648142] Key type .llcrypt registered [10151.996156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10152.412610] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10152.666515] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10152.673657] LNet: Accept secure, port 988 [10154.439708] Key type lgssc registered [10155.685861] Lustre: Echo OBD driver; http://www.lustre.org/ [10165.893631] Lustre: DEBUG MARKER: Iteration 7 [10166.330695] LustreError: 340995:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10166.334775] LustreError: 340996:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10166.358243] LustreError: 340995:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4983 [10167.582100] Lustre: Mounted lustre-client [10168.883834] Lustre: Unmounted lustre-client [10171.550316] Key type lgssc unregistered [10171.760327] LNet: 341346:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10172.833050] LNet: Removed LNI 192.168.202.12@tcp [10173.555521] Key type .llcrypt unregistered [10173.558156] Key type ._llcrypt unregistered [10174.430178] alg: No test for adler32 (adler32-zlib) [10175.200245] Key type ._llcrypt registered [10175.202179] Key type .llcrypt registered [10175.502724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10175.811714] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10176.057100] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10176.065889] LNet: Accept secure, port 988 [10177.751885] Key type lgssc registered [10178.775763] Lustre: Echo OBD driver; http://www.lustre.org/ [10189.584036] Lustre: DEBUG MARKER: Iteration 8 [10189.902904] LustreError: 342142:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10189.904660] LustreError: 342143:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10189.917424] LustreError: 342142:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4992 [10191.162365] Lustre: Mounted lustre-client [10192.269290] Lustre: Unmounted lustre-client [10194.343709] Key type lgssc unregistered [10194.513706] LNet: 342492:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10195.553694] LNet: Removed LNI 192.168.202.12@tcp [10195.983908] Key type .llcrypt unregistered [10195.986182] Key type ._llcrypt unregistered [10196.713616] alg: No test for adler32 (adler32-zlib) [10197.491495] Key type ._llcrypt registered [10197.495593] Key type .llcrypt registered [10197.687557] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10197.937591] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10198.087908] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10198.093834] LNet: Accept secure, port 988 [10199.759507] Key type lgssc registered [10200.737401] Lustre: Echo OBD driver; http://www.lustre.org/ [10210.031903] Lustre: DEBUG MARKER: Iteration 9 [10210.294946] LustreError: 343287:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10210.295493] LustreError: 343288:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10210.311235] LustreError: 343287:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [10211.523742] Lustre: Mounted lustre-client [10213.016448] Lustre: Unmounted lustre-client [10215.235726] Key type lgssc unregistered [10215.450832] LNet: 343635:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10216.483857] LNet: Removed LNI 192.168.202.12@tcp [10217.019801] Key type .llcrypt unregistered [10217.021430] Key type ._llcrypt unregistered [10217.490133] alg: No test for adler32 (adler32-zlib) [10218.292568] Key type ._llcrypt registered [10218.294936] Key type .llcrypt registered [10218.506662] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10218.739455] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10218.991581] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10219.000956] LNet: Accept secure, port 988 [10220.719192] Key type lgssc registered [10221.625689] Lustre: Echo OBD driver; http://www.lustre.org/ [10231.377843] Lustre: DEBUG MARKER: Iteration 10 [10231.817066] LustreError: 344428:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10231.819321] LustreError: 344429:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10231.827492] LustreError: 344428:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10233.124942] Lustre: Mounted lustre-client [10234.635685] Lustre: Unmounted lustre-client [10237.380898] Key type lgssc unregistered [10237.694230] LNet: 344781:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10238.759887] LNet: Removed LNI 192.168.202.12@tcp [10239.338114] Key type .llcrypt unregistered [10239.340413] Key type ._llcrypt unregistered [10240.004371] alg: No test for adler32 (adler32-zlib) [10240.770247] Key type ._llcrypt registered [10240.773990] Key type .llcrypt registered [10240.972639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10241.242569] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10241.424365] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10241.427213] LNet: Accept secure, port 988 [10243.063152] Key type lgssc registered [10243.966339] Lustre: Echo OBD driver; http://www.lustre.org/ [10252.714804] Lustre: DEBUG MARKER: Iteration 11 [10253.096376] LustreError: 345576:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10253.096652] LustreError: 345577:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10253.111238] LustreError: 345576:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4991 [10254.333082] Lustre: Mounted lustre-client [10254.335817] Lustre: Skipped 1 previous similar message [10255.526109] Lustre: Unmounted lustre-client [10257.026985] Key type lgssc unregistered [10257.200858] LNet: 345929:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10258.208890] LNet: Removed LNI 192.168.202.12@tcp [10258.799324] Key type .llcrypt unregistered [10258.800753] Key type ._llcrypt unregistered [10259.371697] alg: No test for adler32 (adler32-zlib) [10260.126466] Key type ._llcrypt registered [10260.128456] Key type .llcrypt registered [10260.334755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10260.672755] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10260.808250] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10260.812669] LNet: Accept secure, port 988 [10262.471251] Key type lgssc registered [10263.478674] Lustre: Echo OBD driver; http://www.lustre.org/ [10279.835635] Lustre: DEBUG MARKER: Iteration 12 [10280.561916] LustreError: 346725:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10280.562400] LustreError: 346726:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10280.590028] LustreError: 346725:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [10282.028690] Lustre: Mounted lustre-client [10283.964342] Lustre: Unmounted lustre-client [10288.080823] Key type lgssc unregistered [10288.374105] LNet: 347077:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10289.441587] LNet: Removed LNI 192.168.202.12@tcp [10290.503980] Key type .llcrypt unregistered [10290.506814] Key type ._llcrypt unregistered [10291.409079] alg: No test for adler32 (adler32-zlib) [10292.170382] Key type ._llcrypt registered [10292.172517] Key type .llcrypt registered [10292.376303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10292.696732] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10292.891674] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10292.896768] LNet: Accept secure, port 988 [10294.551413] Key type lgssc registered [10295.620066] Lustre: Echo OBD driver; http://www.lustre.org/ [10307.063971] Lustre: DEBUG MARKER: Iteration 13 [10307.493105] LustreError: 347873:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10307.493593] LustreError: 347874:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10307.505730] LustreError: 347873:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [10308.722362] Lustre: Mounted lustre-client [10310.193027] Lustre: Unmounted lustre-client [10312.434680] Key type lgssc unregistered [10312.712046] LNet: 348222:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10313.764786] LNet: Removed LNI 192.168.202.12@tcp [10314.449779] Key type .llcrypt unregistered [10314.453596] Key type ._llcrypt unregistered [10315.351101] alg: No test for adler32 (adler32-zlib) [10316.103576] Key type ._llcrypt registered [10316.106126] Key type .llcrypt registered [10316.302896] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10316.561789] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10316.881936] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10316.887895] LNet: Accept secure, port 988 [10318.543239] Key type lgssc registered [10319.870878] Lustre: Echo OBD driver; http://www.lustre.org/ [10333.715736] Lustre: DEBUG MARKER: Iteration 14 [10334.037223] LustreError: 349015:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10334.044334] LustreError: 349018:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10334.049696] LustreError: 349015:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [10335.284864] Lustre: Mounted lustre-client [10336.766668] Lustre: Unmounted lustre-client [10340.085567] Key type lgssc unregistered [10340.241322] LNet: 349368:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10341.280769] LNet: Removed LNI 192.168.202.12@tcp [10341.929249] Key type .llcrypt unregistered [10341.931869] Key type ._llcrypt unregistered [10342.617424] alg: No test for adler32 (adler32-zlib) [10343.387383] Key type ._llcrypt registered [10343.388805] Key type .llcrypt registered [10343.501250] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10343.657613] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10343.774821] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10343.777705] LNet: Accept secure, port 988 [10345.391920] Key type lgssc registered [10346.184834] Lustre: Echo OBD driver; http://www.lustre.org/ [10355.087937] Lustre: DEBUG MARKER: Iteration 15 [10355.343693] LustreError: 350162:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10355.346652] LustreError: 350163:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10355.357497] LustreError: 350162:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10356.585664] Lustre: Mounted lustre-client [10356.588626] Lustre: Skipped 1 previous similar message [10357.981275] Lustre: Unmounted lustre-client [10360.416794] Key type lgssc unregistered [10360.609610] LNet: 350515:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10361.635908] LNet: Removed LNI 192.168.202.12@tcp [10362.318561] Key type .llcrypt unregistered [10362.321489] Key type ._llcrypt unregistered [10363.235608] alg: No test for adler32 (adler32-zlib) [10363.988455] Key type ._llcrypt registered [10363.991498] Key type .llcrypt registered [10364.247107] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10364.525380] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10364.708542] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10364.713880] LNet: Accept secure, port 988 [10366.407234] Key type lgssc registered [10367.275775] Lustre: Echo OBD driver; http://www.lustre.org/ [10379.021906] Lustre: DEBUG MARKER: Iteration 16 [10379.474731] LustreError: 351310:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10379.476026] LustreError: 351311:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10379.493621] LustreError: 351310:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10380.744507] Lustre: Mounted lustre-client [10380.747249] Lustre: Skipped 1 previous similar message [10382.244414] Lustre: Unmounted lustre-client [10384.714271] Key type lgssc unregistered [10384.991144] LNet: 351661:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10386.016990] LNet: Removed LNI 192.168.202.12@tcp [10386.947478] Key type .llcrypt unregistered [10386.949329] Key type ._llcrypt unregistered [10387.691985] alg: No test for adler32 (adler32-zlib) [10388.514955] Key type ._llcrypt registered [10388.520108] Key type .llcrypt registered [10388.867256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10389.129682] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10389.294617] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10389.301279] LNet: Accept secure, port 988 [10391.015083] Key type lgssc registered [10392.149270] Lustre: Echo OBD driver; http://www.lustre.org/ [10404.821861] Lustre: DEBUG MARKER: Iteration 17 [10405.352199] LustreError: 352456:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10405.352760] LustreError: 352457:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10405.374920] LustreError: 352456:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [10406.715639] Lustre: Mounted lustre-client [10408.566546] Lustre: Unmounted lustre-client [10411.508954] Key type lgssc unregistered [10411.726139] LNet: 352808:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10412.769859] LNet: Removed LNI 192.168.202.12@tcp [10413.457365] Key type .llcrypt unregistered [10413.460470] Key type ._llcrypt unregistered [10414.320749] alg: No test for adler32 (adler32-zlib) [10415.086606] Key type ._llcrypt registered [10415.091270] Key type .llcrypt registered [10415.269354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10415.654339] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10415.931239] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10415.942791] LNet: Accept secure, port 988 [10417.583206] Key type lgssc registered [10418.821280] Lustre: Echo OBD driver; http://www.lustre.org/ [10429.488303] Lustre: DEBUG MARKER: Iteration 18 [10429.935481] LustreError: 353603:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10429.935951] LustreError: 353602:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10429.944883] LustreError: 353603:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [10431.271802] Lustre: Mounted lustre-client [10431.273967] Lustre: Skipped 1 previous similar message [10433.317862] Lustre: Unmounted lustre-client [10436.187990] Key type lgssc unregistered [10436.393351] LNet: 353954:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10437.407501] LNet: Removed LNI 192.168.202.12@tcp [10438.126382] Key type .llcrypt unregistered [10438.128657] Key type ._llcrypt unregistered [10438.903529] alg: No test for adler32 (adler32-zlib) [10439.656493] Key type ._llcrypt registered [10439.659199] Key type .llcrypt registered [10439.875202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10440.217571] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10440.444031] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10440.451938] LNet: Accept secure, port 988 [10442.247143] Key type lgssc registered [10443.481435] Lustre: Echo OBD driver; http://www.lustre.org/ [10457.409783] Lustre: DEBUG MARKER: Iteration 19 [10457.939991] LustreError: 354750:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10457.946415] LustreError: 354749:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10457.980274] LustreError: 354750:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4972 [10459.365868] Lustre: Mounted lustre-client [10461.290983] Lustre: Unmounted lustre-client [10464.394916] Key type lgssc unregistered [10464.657570] LNet: 355101:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10465.695961] LNet: Removed LNI 192.168.202.12@tcp [10466.330462] Key type .llcrypt unregistered [10466.332548] Key type ._llcrypt unregistered [10467.001101] alg: No test for adler32 (adler32-zlib) [10467.770829] Key type ._llcrypt registered [10467.775856] Key type .llcrypt registered [10468.135807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10468.553883] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10468.843553] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10468.848673] LNet: Accept secure, port 988 [10470.575216] Key type lgssc registered [10471.399477] Lustre: Echo OBD driver; http://www.lustre.org/ [10482.316315] Lustre: DEBUG MARKER: Iteration 20 [10482.622316] LustreError: 355894:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10482.623957] LustreError: 355900:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10482.642757] LustreError: 355894:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4986 [10483.948662] Lustre: Mounted lustre-client [10485.277305] Lustre: Unmounted lustre-client [10487.991876] Key type lgssc unregistered [10488.221049] LNet: 356244:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10489.250072] LNet: Removed LNI 192.168.202.12@tcp [10489.933598] Key type .llcrypt unregistered [10489.935495] Key type ._llcrypt unregistered [10490.780176] alg: No test for adler32 (adler32-zlib) [10491.546697] Key type ._llcrypt registered [10491.552439] Key type .llcrypt registered [10491.772640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10492.081253] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10492.279504] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10492.283873] LNet: Accept secure, port 988 [10493.951166] Key type lgssc registered [10495.163966] Lustre: Echo OBD driver; http://www.lustre.org/ [10508.466599] Lustre: DEBUG MARKER: Iteration 21 [10508.988199] LustreError: 357039:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10508.994746] LustreError: 357040:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10509.014601] LustreError: 357039:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [10510.479743] Lustre: Mounted lustre-client [10512.623436] Lustre: Unmounted lustre-client [10516.974476] Key type lgssc unregistered [10517.319058] LNet: 357392:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10518.371508] LNet: Removed LNI 192.168.202.12@tcp [10519.527315] Key type .llcrypt unregistered [10519.530432] Key type ._llcrypt unregistered [10520.971321] alg: No test for adler32 (adler32-zlib) [10521.765991] Key type ._llcrypt registered [10521.769557] Key type .llcrypt registered [10522.141303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10522.738664] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10523.053103] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10523.067701] LNet: Accept secure, port 988 [10524.919265] Key type lgssc registered [10527.003351] Lustre: Echo OBD driver; http://www.lustre.org/ [10540.193530] Lustre: DEBUG MARKER: Iteration 22 [10540.647631] LustreError: 358187:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10540.648053] LustreError: 358188:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10540.670348] LustreError: 358187:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [10542.017549] Lustre: Mounted lustre-client [10542.026097] Lustre: Skipped 1 previous similar message [10543.592277] Lustre: Unmounted lustre-client [10546.073646] Key type lgssc unregistered [10546.301449] LNet: 358534:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10547.369520] LNet: Removed LNI 192.168.202.12@tcp [10548.268845] Key type .llcrypt unregistered [10548.274838] Key type ._llcrypt unregistered [10549.089280] alg: No test for adler32 (adler32-zlib) [10549.841544] Key type ._llcrypt registered [10549.843992] Key type .llcrypt registered [10550.020970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10550.298892] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10550.449336] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10550.452159] LNet: Accept secure, port 988 [10552.073158] Key type lgssc registered [10553.422516] Lustre: Echo OBD driver; http://www.lustre.org/ [10569.145280] Lustre: DEBUG MARKER: Iteration 23 [10569.594774] LustreError: 359329:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10569.595714] LustreError: 359331:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10569.626399] LustreError: 359329:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4983 [10571.050809] Lustre: Mounted lustre-client [10571.059676] Lustre: Skipped 1 previous similar message [10573.151788] Lustre: Unmounted lustre-client [10576.345824] Key type lgssc unregistered [10576.786634] LNet: 359684:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10577.824451] LNet: Removed LNI 192.168.202.12@tcp [10578.586760] Key type .llcrypt unregistered [10578.589150] Key type ._llcrypt unregistered [10579.822361] alg: No test for adler32 (adler32-zlib) [10580.716460] Key type ._llcrypt registered [10580.717808] Key type .llcrypt registered [10580.906401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10581.118876] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10581.269569] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10581.272523] LNet: Accept secure, port 988 [10582.943122] Key type lgssc registered [10583.873577] Lustre: Echo OBD driver; http://www.lustre.org/ [10594.226806] Lustre: DEBUG MARKER: Iteration 24 [10594.633689] LustreError: 360478:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10594.644764] LustreError: 360481:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10594.655925] LustreError: 360478:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4985 [10595.958022] Lustre: Mounted lustre-client [10597.571282] Lustre: Unmounted lustre-client [10600.123591] Key type lgssc unregistered [10600.375525] LNet: 360828:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10601.440776] LNet: Removed LNI 192.168.202.12@tcp [10602.092964] Key type .llcrypt unregistered [10602.097446] Key type ._llcrypt unregistered [10602.728501] alg: No test for adler32 (adler32-zlib) [10603.486783] Key type ._llcrypt registered [10603.488741] Key type .llcrypt registered [10603.692740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10603.982174] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10604.158356] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10604.161438] LNet: Accept secure, port 988 [10605.823160] Key type lgssc registered [10606.864461] Lustre: Echo OBD driver; http://www.lustre.org/ [10616.157784] Lustre: DEBUG MARKER: Iteration 25 [10616.385761] LustreError: 361623:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10616.386168] LustreError: 361624:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10616.395510] LustreError: 361623:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10617.630827] Lustre: Mounted lustre-client [10619.148564] Lustre: Unmounted lustre-client [10621.798937] Key type lgssc unregistered [10622.082465] LNet: 361974:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10623.147481] LNet: Removed LNI 192.168.202.12@tcp [10623.913915] Key type .llcrypt unregistered [10623.916837] Key type ._llcrypt unregistered [10624.782224] alg: No test for adler32 (adler32-zlib) [10625.546487] Key type ._llcrypt registered [10625.548538] Key type .llcrypt registered [10625.741499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10626.069650] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10626.287759] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10626.291467] LNet: Accept secure, port 988 [10628.007116] Key type lgssc registered [10629.098811] Lustre: Echo OBD driver; http://www.lustre.org/ [10641.853840] Lustre: DEBUG MARKER: Iteration 26 [10642.344614] LustreError: 362768:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10642.349413] LustreError: 362769:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10642.365256] LustreError: 362768:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4987 [10643.685608] Lustre: Mounted lustre-client [10645.405855] Lustre: Unmounted lustre-client [10648.191484] Key type lgssc unregistered [10648.407739] LNet: 363118:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10649.441062] LNet: Removed LNI 192.168.202.12@tcp [10650.101308] Key type .llcrypt unregistered [10650.104579] Key type ._llcrypt unregistered [10651.470287] alg: No test for adler32 (adler32-zlib) [10652.220856] Key type ._llcrypt registered [10652.228738] Key type .llcrypt registered [10652.534379] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10652.895231] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10653.142116] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10653.148538] LNet: Accept secure, port 988 [10654.847232] Key type lgssc registered [10656.264116] Lustre: Echo OBD driver; http://www.lustre.org/ [10668.482840] Lustre: DEBUG MARKER: Iteration 27 [10668.930516] LustreError: 363915:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10668.930934] LustreError: 363916:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10668.941405] LustreError: 363915:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4996 [10670.320601] Lustre: Mounted lustre-client [10670.329062] Lustre: Skipped 1 previous similar message [10671.811438] Lustre: Unmounted lustre-client [10674.898461] Key type lgssc unregistered [10675.193817] LNet: 364271:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10676.258748] LNet: Removed LNI 192.168.202.12@tcp [10676.962721] Key type .llcrypt unregistered [10676.968712] Key type ._llcrypt unregistered [10677.886230] alg: No test for adler32 (adler32-zlib) [10678.649585] Key type ._llcrypt registered [10678.660169] Key type .llcrypt registered [10678.986853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10679.445201] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10679.648800] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10679.653732] LNet: Accept secure, port 988 [10681.343137] Key type lgssc registered [10682.703480] Lustre: Echo OBD driver; http://www.lustre.org/ [10696.095487] Lustre: DEBUG MARKER: Iteration 28 [10696.537586] LustreError: 365065:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10696.539122] LustreError: 365066:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10696.568454] LustreError: 365065:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4972 [10697.940857] Lustre: Mounted lustre-client [10699.669184] Lustre: Unmounted lustre-client [10702.261167] Key type lgssc unregistered [10702.523241] LNet: 365418:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10703.588842] LNet: Removed LNI 192.168.202.12@tcp [10704.404912] Key type .llcrypt unregistered [10704.411913] Key type ._llcrypt unregistered [10705.466156] alg: No test for adler32 (adler32-zlib) [10706.236739] Key type ._llcrypt registered [10706.241600] Key type .llcrypt registered [10706.443165] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10706.798890] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10707.004750] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10707.008461] LNet: Accept secure, port 988 [10708.695297] Key type lgssc registered [10710.151242] Lustre: Echo OBD driver; http://www.lustre.org/ [10723.164361] Lustre: DEBUG MARKER: Iteration 29 [10723.481823] LustreError: 366214:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10723.492252] LustreError: 366215:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10723.499861] LustreError: 366214:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10724.860816] Lustre: Mounted lustre-client [10726.018235] Lustre: Unmounted lustre-client [10728.420569] Key type lgssc unregistered [10728.674248] LNet: 366569:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10729.698921] LNet: Removed LNI 192.168.202.12@tcp [10730.469379] Key type .llcrypt unregistered [10730.471826] Key type ._llcrypt unregistered [10731.341929] alg: No test for adler32 (adler32-zlib) [10732.136163] Key type ._llcrypt registered [10732.138586] Key type .llcrypt registered [10732.442559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10732.926849] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10733.270973] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10733.275909] LNet: Accept secure, port 988 [10734.983153] Key type lgssc registered [10736.183732] Lustre: Echo OBD driver; http://www.lustre.org/ [10747.683683] Lustre: DEBUG MARKER: Iteration 30 [10747.994190] LustreError: 367364:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10747.995129] LustreError: 367366:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10748.009335] LustreError: 367364:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [10749.297619] Lustre: Mounted lustre-client [10750.787642] Lustre: Unmounted lustre-client [10753.425754] Key type lgssc unregistered [10753.677569] LNet: 367713:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10754.729220] LNet: Removed LNI 192.168.202.12@tcp [10755.435821] Key type .llcrypt unregistered [10755.438627] Key type ._llcrypt unregistered [10756.539355] alg: No test for adler32 (adler32-zlib) [10757.331824] Key type ._llcrypt registered [10757.336735] Key type .llcrypt registered [10757.597428] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10757.975776] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10758.137591] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10758.142751] LNet: Accept secure, port 988 [10759.815592] Key type lgssc registered [10761.009540] Lustre: Echo OBD driver; http://www.lustre.org/ [10773.485374] Lustre: DEBUG MARKER: Iteration 31 [10773.930890] LustreError: 368509:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10773.932218] LustreError: 368510:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10773.967350] LustreError: 368509:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4972 [10775.284707] Lustre: Mounted lustre-client [10776.996452] Lustre: Unmounted lustre-client [10780.068641] Key type lgssc unregistered [10780.306918] LNet: 368861:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10781.349086] LNet: Removed LNI 192.168.202.12@tcp [10782.019402] Key type .llcrypt unregistered [10782.020994] Key type ._llcrypt unregistered [10782.840419] alg: No test for adler32 (adler32-zlib) [10783.600773] Key type ._llcrypt registered [10783.608226] Key type .llcrypt registered [10783.851413] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10784.165777] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10784.438211] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10784.442979] LNet: Accept secure, port 988 [10786.128499] Key type lgssc registered [10787.131790] Lustre: Echo OBD driver; http://www.lustre.org/ [10798.037181] Lustre: DEBUG MARKER: Iteration 32 [10798.322544] LustreError: 369655:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10798.331590] LustreError: 369657:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10798.337388] LustreError: 369655:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4994 [10799.622245] Lustre: Mounted lustre-client [10801.930858] Lustre: Unmounted lustre-client [10805.110748] Key type lgssc unregistered [10805.340905] LNet: 370008:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10806.369057] LNet: Removed LNI 192.168.202.12@tcp [10807.374292] Key type .llcrypt unregistered [10807.377550] Key type ._llcrypt unregistered [10808.344437] alg: No test for adler32 (adler32-zlib) [10809.126100] Key type ._llcrypt registered [10809.133433] Key type .llcrypt registered [10809.482149] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10810.008442] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10810.229336] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10810.233813] LNet: Accept secure, port 988 [10811.921415] Key type lgssc registered [10813.468941] Lustre: Echo OBD driver; http://www.lustre.org/ [10828.082420] Lustre: DEBUG MARKER: Iteration 33 [10828.699106] LustreError: 370803:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10828.701804] LustreError: 370805:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10828.709382] LustreError: 370803:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [10830.012964] Lustre: Mounted lustre-client [10832.347595] Lustre: Unmounted lustre-client [10836.545559] Key type lgssc unregistered [10836.787896] LNet: 371160:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10837.861884] LNet: Removed LNI 192.168.202.12@tcp [10838.516626] Key type .llcrypt unregistered [10838.519167] Key type ._llcrypt unregistered [10839.401029] alg: No test for adler32 (adler32-zlib) [10840.196617] Key type ._llcrypt registered [10840.198775] Key type .llcrypt registered [10840.401919] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10840.636799] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10840.804929] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10840.807623] LNet: Accept secure, port 988 [10842.463131] Key type lgssc registered [10843.435737] Lustre: Echo OBD driver; http://www.lustre.org/ [10855.004232] Lustre: DEBUG MARKER: Iteration 34 [10855.300577] LustreError: 371957:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10855.300790] LustreError: 371956:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10855.316621] LustreError: 371957:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [10856.570972] Lustre: Mounted lustre-client [10856.579392] Lustre: Skipped 1 previous similar message [10858.430736] Lustre: Unmounted lustre-client [10860.909256] Key type lgssc unregistered [10861.060168] LNet: 372306:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10862.113246] LNet: Removed LNI 192.168.202.12@tcp [10862.639907] Key type .llcrypt unregistered [10862.643361] Key type ._llcrypt unregistered [10863.657245] alg: No test for adler32 (adler32-zlib) [10864.506666] Key type ._llcrypt registered [10864.511760] Key type .llcrypt registered [10864.828202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10865.164736] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10865.379328] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10865.382854] LNet: Accept secure, port 988 [10867.065173] Key type lgssc registered [10868.210598] Lustre: Echo OBD driver; http://www.lustre.org/ [10879.871562] Lustre: DEBUG MARKER: Iteration 35 [10880.307429] LustreError: 373098:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10880.307668] LustreError: 373100:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10880.318876] LustreError: 373098:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [10881.720406] Lustre: Mounted lustre-client [10883.484899] Lustre: Unmounted lustre-client [10886.532301] Key type lgssc unregistered [10886.661955] LNet: 373452:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10887.713389] LNet: Removed LNI 192.168.202.12@tcp [10888.839695] Key type .llcrypt unregistered [10888.846408] Key type ._llcrypt unregistered [10890.744711] alg: No test for adler32 (adler32-zlib) [10891.524996] Key type ._llcrypt registered [10891.526536] Key type .llcrypt registered [10891.824211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10892.314957] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10892.726694] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10892.734382] LNet: Accept secure, port 988 [10894.519216] Key type lgssc registered [10896.013406] Lustre: Echo OBD driver; http://www.lustre.org/ [10909.750295] Lustre: DEBUG MARKER: Iteration 36 [10910.195963] LustreError: 374248:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10910.203940] LustreError: 374249:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10910.229609] LustreError: 374248:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [10911.548285] Lustre: Mounted lustre-client [10912.717622] Lustre: Unmounted lustre-client [10915.271317] Key type lgssc unregistered [10915.529176] LNet: 374599:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10916.582257] LNet: Removed LNI 192.168.202.12@tcp [10917.149705] Key type .llcrypt unregistered [10917.151781] Key type ._llcrypt unregistered [10917.969188] alg: No test for adler32 (adler32-zlib) [10918.753961] Key type ._llcrypt registered [10918.756609] Key type .llcrypt registered [10919.022529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10919.374554] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10919.580557] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10919.584354] LNet: Accept secure, port 988 [10921.263139] Key type lgssc registered [10922.600582] Lustre: Echo OBD driver; http://www.lustre.org/ [10934.487554] Lustre: DEBUG MARKER: Iteration 37 [10934.936241] LustreError: 375393:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10934.936292] LustreError: 375395:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10934.948284] LustreError: 375393:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [10936.314531] Lustre: Mounted lustre-client [10938.980780] Lustre: Unmounted lustre-client [10941.932575] Key type lgssc unregistered [10942.189763] LNet: 375744:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10943.200582] LNet: Removed LNI 192.168.202.12@tcp [10944.082496] Key type .llcrypt unregistered [10944.086634] Key type ._llcrypt unregistered [10945.975772] alg: No test for adler32 (adler32-zlib) [10946.803652] Key type ._llcrypt registered [10946.813931] Key type .llcrypt registered [10947.159541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10947.581677] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10947.815022] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10947.820228] LNet: Accept secure, port 988 [10949.561101] Key type lgssc registered [10950.735203] Lustre: Echo OBD driver; http://www.lustre.org/ [10965.016645] Lustre: DEBUG MARKER: Iteration 38 [10965.439982] LustreError: 376538:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10965.439996] LustreError: 376539:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10965.466082] LustreError: 376538:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [10966.783175] Lustre: Mounted lustre-client [10966.796141] Lustre: Skipped 1 previous similar message [10968.427128] Lustre: Unmounted lustre-client [10971.836837] Key type lgssc unregistered [10972.111926] LNet: 376892:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10973.153191] LNet: Removed LNI 192.168.202.12@tcp [10973.905294] Key type .llcrypt unregistered [10973.907699] Key type ._llcrypt unregistered [10974.856866] alg: No test for adler32 (adler32-zlib) [10975.653796] Key type ._llcrypt registered [10975.656638] Key type .llcrypt registered [10975.941869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10976.385833] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [10976.626708] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [10976.631434] LNet: Accept secure, port 988 [10978.295260] Key type lgssc registered [10979.618773] Lustre: Echo OBD driver; http://www.lustre.org/ [10992.200188] Lustre: DEBUG MARKER: Iteration 39 [10992.651706] LustreError: 377686:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [10992.654130] LustreError: 377687:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [10992.675322] LustreError: 377686:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4984 [10994.105207] Lustre: Mounted lustre-client [10995.619237] Lustre: Unmounted lustre-client [10998.681680] Key type lgssc unregistered [10998.935173] LNet: 378037:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10999.974787] LNet: Removed LNI 192.168.202.12@tcp [11000.839473] Key type .llcrypt unregistered [11000.843240] Key type ._llcrypt unregistered [11001.728478] alg: No test for adler32 (adler32-zlib) [11002.541677] Key type ._llcrypt registered [11002.547308] Key type .llcrypt registered [11002.823303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11003.111137] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11003.374031] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11003.382587] LNet: Accept secure, port 988 [11005.127920] Key type lgssc registered [11006.226953] Lustre: Echo OBD driver; http://www.lustre.org/ [11018.086919] Lustre: DEBUG MARKER: Iteration 40 [11018.410105] LustreError: 378828:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11018.413669] LustreError: 378830:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11018.432788] LustreError: 378828:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [11019.766596] Lustre: Mounted lustre-client [11021.156200] Lustre: Unmounted lustre-client [11023.508486] Key type lgssc unregistered [11023.744805] LNet: 379183:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11024.800414] LNet: Removed LNI 192.168.202.12@tcp [11025.407583] Key type .llcrypt unregistered [11025.410340] Key type ._llcrypt unregistered [11026.243711] alg: No test for adler32 (adler32-zlib) [11027.000368] Key type ._llcrypt registered [11027.005191] Key type .llcrypt registered [11027.261477] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11027.501695] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11027.864869] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11027.868623] LNet: Accept secure, port 988 [11029.647809] Key type lgssc registered [11030.653819] Lustre: Echo OBD driver; http://www.lustre.org/ [11046.382133] Lustre: DEBUG MARKER: Iteration 41 [11046.752909] LustreError: 379974:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11046.757329] LustreError: 379983:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11046.770568] LustreError: 379974:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4989 [11048.109827] Lustre: Mounted lustre-client [11048.115218] Lustre: Skipped 1 previous similar message [11049.868508] Lustre: Unmounted lustre-client [11052.906127] Key type lgssc unregistered [11053.087168] LNet: 380332:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11054.128354] LNet: Removed LNI 192.168.202.12@tcp [11054.700870] Key type .llcrypt unregistered [11054.702509] Key type ._llcrypt unregistered [11055.197548] alg: No test for adler32 (adler32-zlib) [11055.956515] Key type ._llcrypt registered [11055.959275] Key type .llcrypt registered [11056.251264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11056.598866] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11056.968770] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11056.972102] LNet: Accept secure, port 988 [11058.623312] Key type lgssc registered [11059.720455] Lustre: Echo OBD driver; http://www.lustre.org/ [11071.809147] Lustre: DEBUG MARKER: Iteration 42 [11072.571328] LustreError: 381127:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11072.572153] LustreError: 381128:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11072.587276] LustreError: 381127:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=5000 [11073.995758] Lustre: Mounted lustre-client [11075.685266] Lustre: Unmounted lustre-client [11078.319500] Key type lgssc unregistered [11078.558356] LNet: 381484:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11079.585522] LNet: Removed LNI 192.168.202.12@tcp [11080.354549] Key type .llcrypt unregistered [11080.359118] Key type ._llcrypt unregistered [11081.391103] alg: No test for adler32 (adler32-zlib) [11082.148591] Key type ._llcrypt registered [11082.151431] Key type .llcrypt registered [11082.366840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11082.703781] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11082.882585] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11082.886469] LNet: Accept secure, port 988 [11084.551081] Key type lgssc registered [11085.789267] Lustre: Echo OBD driver; http://www.lustre.org/ [11096.841872] Lustre: DEBUG MARKER: Iteration 43 [11097.216489] LustreError: 382280:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11097.219166] LustreError: 382279:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11097.232768] LustreError: 382280:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4983 [11098.429648] Lustre: Mounted lustre-client [11099.874064] Lustre: Unmounted lustre-client [11103.068798] Key type lgssc unregistered [11103.306152] LNet: 382631:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11104.352816] LNet: Removed LNI 192.168.202.12@tcp [11104.942396] Key type .llcrypt unregistered [11104.944650] Key type ._llcrypt unregistered [11106.042991] alg: No test for adler32 (adler32-zlib) [11106.823817] Key type ._llcrypt registered [11106.826358] Key type .llcrypt registered [11107.027052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11107.313091] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11107.492947] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11107.496616] LNet: Accept secure, port 988 [11109.151141] Key type lgssc registered [11110.227526] Lustre: Echo OBD driver; http://www.lustre.org/ [11120.766335] Lustre: DEBUG MARKER: Iteration 44 [11121.099213] LustreError: 383425:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11121.101359] LustreError: 383426:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11121.114134] LustreError: 383425:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4995 [11122.316347] Lustre: Mounted lustre-client [11124.320469] Lustre: Unmounted lustre-client [11128.283951] Key type lgssc unregistered [11128.658899] LNet: 383777:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11129.698057] LNet: Removed LNI 192.168.202.12@tcp [11130.763574] Key type .llcrypt unregistered [11130.765049] Key type ._llcrypt unregistered [11131.952132] alg: No test for adler32 (adler32-zlib) [11132.795452] Key type ._llcrypt registered [11132.797226] Key type .llcrypt registered [11132.943089] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11133.275182] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11133.670846] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11133.676554] LNet: Accept secure, port 988 [11135.447124] Key type lgssc registered [11137.371498] Lustre: Echo OBD driver; http://www.lustre.org/ [11150.234402] Lustre: DEBUG MARKER: Iteration 45 [11150.549780] LustreError: 384571:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11150.552854] LustreError: 384572:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11150.576859] LustreError: 384571:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4988 [11151.919466] Lustre: Mounted lustre-client [11153.587371] Lustre: Unmounted lustre-client [11156.013958] Key type lgssc unregistered [11156.195363] LNet: 384923:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11157.224741] LNet: Removed LNI 192.168.202.12@tcp [11157.987316] Key type .llcrypt unregistered [11157.989435] Key type ._llcrypt unregistered [11158.664053] alg: No test for adler32 (adler32-zlib) [11159.441954] Key type ._llcrypt registered [11159.446189] Key type .llcrypt registered [11159.695080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11159.974507] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11160.243354] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11160.246263] LNet: Accept secure, port 988 [11161.943116] Key type lgssc registered [11163.262300] Lustre: Echo OBD driver; http://www.lustre.org/ [11175.807988] Lustre: DEBUG MARKER: Iteration 46 [11176.165953] LustreError: 385713:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11176.167411] LustreError: 385716:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11176.183221] LustreError: 385713:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4990 [11177.512273] Lustre: Mounted lustre-client [11178.705293] Lustre: Unmounted lustre-client [11181.148130] Key type lgssc unregistered [11181.446052] LNet: 386071:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11182.499212] LNet: Removed LNI 192.168.202.12@tcp [11183.151039] Key type .llcrypt unregistered [11183.152864] Key type ._llcrypt unregistered [11183.840227] alg: No test for adler32 (adler32-zlib) [11184.599451] Key type ._llcrypt registered [11184.605933] Key type .llcrypt registered [11184.794821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11185.085210] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11185.324882] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11185.327735] LNet: Accept secure, port 988 [11186.991155] Key type lgssc registered [11188.147302] Lustre: Echo OBD driver; http://www.lustre.org/ [11202.849748] Lustre: DEBUG MARKER: Iteration 47 [11203.173791] LustreError: 386867:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11203.174293] LustreError: 386868:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11203.185272] LustreError: 386867:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4997 [11204.484644] Lustre: Mounted lustre-client [11206.403535] Lustre: Unmounted lustre-client [11209.551089] Key type lgssc unregistered [11209.838539] LNet: 387221:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11210.858797] LNet: Removed LNI 192.168.202.12@tcp [11211.598241] Key type .llcrypt unregistered [11211.607478] Key type ._llcrypt unregistered [11212.639689] alg: No test for adler32 (adler32-zlib) [11213.418840] Key type ._llcrypt registered [11213.424302] Key type .llcrypt registered [11213.651405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11213.928843] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11214.176734] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11214.195575] LNet: Accept secure, port 988 [11215.943638] Key type lgssc registered [11217.457856] Lustre: Echo OBD driver; http://www.lustre.org/ [11228.743604] Lustre: DEBUG MARKER: Iteration 48 [11229.092125] LustreError: 388015:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11229.094028] LustreError: 388016:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11229.107178] LustreError: 388015:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4993 [11230.381120] Lustre: Mounted lustre-client [11231.781186] Lustre: Unmounted lustre-client [11234.116398] Key type lgssc unregistered [11234.346799] LNet: 388371:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11235.361946] LNet: Removed LNI 192.168.202.12@tcp [11236.041992] Key type .llcrypt unregistered [11236.043899] Key type ._llcrypt unregistered [11236.878530] alg: No test for adler32 (adler32-zlib) [11237.638456] Key type ._llcrypt registered [11237.639973] Key type .llcrypt registered [11237.821807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11238.199195] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11238.410550] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11238.414518] LNet: Accept secure, port 988 [11240.065996] Key type lgssc registered [11241.124486] Lustre: Echo OBD driver; http://www.lustre.org/ [11253.859957] Lustre: DEBUG MARKER: Iteration 49 [11254.192825] LustreError: 389163:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11254.192853] LustreError: 389165:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11254.215421] LustreError: 389163:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4980 [11255.537919] Lustre: Mounted lustre-client [11257.291714] Lustre: Unmounted lustre-client [11260.549403] Key type lgssc unregistered [11260.842304] LNet: 389514:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11261.858915] LNet: Removed LNI 192.168.202.12@tcp [11262.673145] Key type .llcrypt unregistered [11262.675761] Key type ._llcrypt unregistered [11263.565805] alg: No test for adler32 (adler32-zlib) [11264.331327] Key type ._llcrypt registered [11264.337718] Key type .llcrypt registered [11264.656615] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11265.095363] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11265.305392] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11265.311758] LNet: Accept secure, port 988 [11267.039145] Key type lgssc registered [11268.450473] Lustre: Echo OBD driver; http://www.lustre.org/ [11280.445950] Lustre: DEBUG MARKER: Iteration 50 [11280.825298] LustreError: 390310:0:(libcfs_fail.h:169:cfs_race()) cfs_race id 1417 sleeping [11280.826672] LustreError: 390311:0:(libcfs_fail.h:180:cfs_race()) cfs_fail_race id 1417 waking [11280.846708] LustreError: 390310:0:(libcfs_fail.h:178:cfs_race()) cfs_fail_race id 1417 awake: rc=4983 [11282.166718] Lustre: Mounted lustre-client [11282.173788] Lustre: Skipped 1 previous similar message [11283.676992] Lustre: Unmounted lustre-client [11286.503143] Key type lgssc unregistered [11286.821401] LNet: 390659:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11287.908304] LNet: Removed LNI 192.168.202.12@tcp [11288.575700] Key type .llcrypt unregistered [11288.577924] Key type ._llcrypt unregistered [11289.368578] alg: No test for adler32 (adler32-zlib) [11290.124777] Key type ._llcrypt registered [11290.128544] Key type .llcrypt registered [11290.431227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11290.777963] Lustre: Lustre: Build Version: 2.15.8_10_g54e838e [11291.113666] LNet: Added LNI 192.168.202.12@tcp [8/256/0/180] [11291.119609] LNet: Accept secure, port 988 [11292.831242] Key type lgssc registered [11294.092258] Lustre: Echo OBD driver; http://www.lustre.org/ [11306.394607] Lustre: Mounted lustre-client [11313.088411] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 17:32:04 (1776202324) [11321.823218] Lustre: 391968:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776202327/real 1776202327] req@00000000d51c45c6 x1862483106075968/t0(0) o36->lustre-MDT0000-mdc-ffff8a5242e3c800@192.168.202.112@tcp:12/10 lens 496/440 e 0 to 1 dl 1776202334 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'ln.0' [11321.900312] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11321.986419] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection restored to (at 192.168.202.112@tcp) [11328.479189] Lustre: 391968:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776202334/real 1776202334] req@00000000d51c45c6 x1862483106075968/t0(0) o36->lustre-MDT0000-mdc-ffff8a5242e3c800@192.168.202.112@tcp:12/10 lens 496/440 e 0 to 1 dl 1776202341 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [11328.517112] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11328.571106] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection restored to (at 192.168.202.112@tcp) [11335.651924] Lustre: 391968:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776202341/real 1776202341] req@00000000d51c45c6 x1862483106075968/t0(0) o36->lustre-MDT0000-mdc-ffff8a5242e3c800@192.168.202.112@tcp:12/10 lens 496/440 e 0 to 1 dl 1776202348 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [11335.701666] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11335.750513] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection restored to (at 192.168.202.112@tcp) [11342.815165] Lustre: 391968:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776202348/real 1776202348] req@00000000d51c45c6 x1862483106075968/t0(0) o36->lustre-MDT0000-mdc-ffff8a5242e3c800@192.168.202.112@tcp:12/10 lens 496/440 e 0 to 1 dl 1776202355 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [11342.849494] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11342.884440] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection restored to (at 192.168.202.112@tcp) [11349.984505] Lustre: 391968:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1776202355/real 1776202355] req@00000000d51c45c6 x1862483106075968/t0(0) o36->lustre-MDT0000-mdc-ffff8a5242e3c800@192.168.202.112@tcp:12/10 lens 496/440 e 0 to 1 dl 1776202362 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'ln.0' [11350.004759] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection to lustre-MDT0000 (at 192.168.202.112@tcp) was lost; in progress operations using this service will wait for recovery to complete [11350.054752] Lustre: lustre-MDT0000-mdc-ffff8a5242e3c800: Connection restored to (at 192.168.202.112@tcp) [11355.330039] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 17:32:46 (1776202366) [11368.229432] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 17:32:58 (1776202378) [11380.054509] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 17:33:11 (1776202391) [11387.513248] Lustre: DEBUG MARKER: cleanup: ====================================================== [11389.746242] Lustre: DEBUG MARKER: == sanityn test complete, duration 11057 sec ============= 17:33:20 (1776202400) [11632.208885] Lustre: Unmounted lustre-client [11634.157154] Lustre: Unmounted lustre-client [11686.977250] Key type lgssc unregistered [11687.243602] LNet: 395261:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11688.300239] LNet: Removed LNI 192.168.202.12@tcp [11689.057197] Key type .llcrypt unregistered [11689.062128] Key type ._llcrypt unregistered