[ 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 428038282 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001014] APIC: Switch to symmetric I/O mode setup [ 0.003277] x2apic enabled [ 0.004011] Switched APIC routing to physical x2apic. [ 0.005020] kvm-guest: setup PV IPIs [ 0.008575] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009030] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010018] pid_max: default: 32768 minimum: 301 [ 0.011153] LSM: Security Framework initializing [ 0.012077] Yama: becoming mindful. [ 0.013052] SELinux: Initializing. [ 0.015087] *** VALIDATE selinux *** [ 0.023855] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028327] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029180] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031126] *** VALIDATE tmpfs *** [ 0.033460] *** VALIDATE proc *** [ 0.035071] *** VALIDATE cgroup *** [ 0.036014] *** VALIDATE cgroup2 *** [ 0.038119] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039174] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040013] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.042026] Spectre V2 : User space: Vulnerable [ 0.043014] Speculative Store Bypass: Vulnerable [ 0.046019] debug: unmapping init [mem 0xffffffff8ce59000-0xffffffff8ce60fff] [ 0.048248] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049782] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050025] ... version: 2 [ 0.051015] ... bit width: 48 [ 0.052014] ... generic registers: 4 [ 0.053013] ... value mask: 0000ffffffffffff [ 0.054012] ... max period: 00007fffffffffff [ 0.055014] ... fixed-purpose events: 3 [ 0.055967] ... event mask: 000000070000000f [ 0.057261] rcu: Hierarchical SRCU implementation. [ 0.059483] smp: Bringing up secondary CPUs ... [ 0.060650] x86: Booting SMP configuration: [ 0.061039] .... node #0, CPUs: #1 #2 #3 [ 0.065455] smp: Brought up 1 node, 4 CPUs [ 0.067021] smpboot: Max logical packages: 1 [ 0.068017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.237128] node 0 deferred pages initialised in 167ms [ 0.239184] devtmpfs: initialized [ 0.240261] x86/mm: Memory block size: 128MB [ 0.242627] gcov: version magic: 0x41383552 [ 0.245236] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.248126] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.251364] pinctrl core: initialized pinctrl subsystem [ 0.253225] [ 0.253759] ************************************************************* [ 0.255019] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.258013] ** ** [ 0.260014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.261012] ** ** [ 0.263015] ** This means that this kernel is built to expose internal ** [ 0.265011] ** IOMMU data structures, which may compromise security on ** [ 0.267014] ** your system. ** [ 0.269013] ** ** [ 0.270010] ** If you see this message and you are not debugging the ** [ 0.272014] ** kernel, report this immediately to your vendor! ** [ 0.274014] ** ** [ 0.276015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.278014] ************************************************************* [ 0.280593] NET: Registered protocol family 16 [ 0.281444] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.283048] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.285043] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.287117] cpuidle: using governor menu [ 0.288814] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.291483] PCI: Using configuration type 1 for base access [ 0.293140] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.302116] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.304039] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.307100] cryptd: max_cpu_qlen set to 1000 [ 0.309202] ACPI: Added _OSI(Module Device) [ 0.310016] ACPI: Added _OSI(Processor Device) [ 0.311009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.312010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.316858] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.320444] ACPI: Interpreter enabled [ 0.321075] ACPI: PM: (supports S0 S3 S4 S5) [ 0.322011] ACPI: Using IOAPIC for interrupt routing [ 0.323131] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.325285] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.332276] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.334033] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.336020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.338088] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.342164] acpiphp: Slot [2] registered [ 0.343112] acpiphp: Slot [5] registered [ 0.344115] acpiphp: Slot [6] registered [ 0.345096] acpiphp: Slot [3] registered [ 0.346093] acpiphp: Slot [4] registered [ 0.347069] acpiphp: Slot [7] registered [ 0.348085] acpiphp: Slot [8] registered [ 0.349076] acpiphp: Slot [9] registered [ 0.350083] acpiphp: Slot [10] registered [ 0.351073] acpiphp: Slot [11] registered [ 0.352069] acpiphp: Slot [12] registered [ 0.354072] acpiphp: Slot [13] registered [ 0.355087] acpiphp: Slot [14] registered [ 0.356000] acpiphp: Slot [15] registered [ 0.356000] acpiphp: Slot [16] registered [ 0.358077] acpiphp: Slot [17] registered [ 0.359065] acpiphp: Slot [18] registered [ 0.360017] acpiphp: Slot [19] registered [ 0.361017] acpiphp: Slot [20] registered [ 0.361929] acpiphp: Slot [21] registered [ 0.362098] acpiphp: Slot [22] registered [ 0.363058] acpiphp: Slot [23] registered [ 0.364066] acpiphp: Slot [24] registered [ 0.365071] acpiphp: Slot [25] registered [ 0.366049] acpiphp: Slot [26] registered [ 0.367083] acpiphp: Slot [27] registered [ 0.368022] acpiphp: Slot [28] registered [ 0.368931] acpiphp: Slot [29] registered [ 0.370065] acpiphp: Slot [30] registered [ 0.371067] acpiphp: Slot [31] registered [ 0.371990] PCI host bridge to bus 0000:00 [ 0.373017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.374016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.376022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.377021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.379024] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.380000] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.380000] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.382854] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.385192] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.390459] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.393631] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.395020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.397016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.398013] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.400584] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.402597] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.404085] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.407773] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.410961] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.420018] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.425016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.430820] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.437021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.442018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.456023] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.464284] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.467896] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.471018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.479859] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.486730] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.488251] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.491416] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.493324] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.494147] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.497118] iommu: Default domain type: Passthrough [ 0.499548] SCSI subsystem initialized [ 0.501197] ACPI: bus type USB registered [ 0.503132] usbcore: registered new interface driver usbfs [ 0.505113] usbcore: registered new interface driver hub [ 0.507095] usbcore: registered new device driver usb [ 0.508183] pps_core: LinuxPPS API ver. 1 registered [ 0.510013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.512068] PTP clock support registered [ 0.514122] EDAC MC: Ver: 3.0.0 [ 0.516162] PCI: Using ACPI for IRQ routing [ 0.517836] NetLabel: Initializing [ 0.518010] NetLabel: domain hash size = 128 [ 0.519012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.521100] NetLabel: unlabeled traffic allowed by default [ 0.524108] vgaarb: loaded [ 0.525207] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.526010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.533291] clocksource: Switched to clocksource kvm-clock [ 0.627627] VFS: Disk quotas dquot_6.6.0 [ 0.628878] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.630642] *** VALIDATE ramfs *** [ 0.631592] *** VALIDATE hugetlbfs *** [ 0.632635] pnp: PnP ACPI init [ 0.634429] pnp: PnP ACPI: found 6 devices [ 0.648275] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.650434] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.652095] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.653987] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.656402] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.658372] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.661150] NET: Registered protocol family 2 [ 0.663331] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.668020] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.671156] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.675708] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.678351] TCP: Hash tables configured (established 65536 bind 65536) [ 0.680869] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.683418] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.685466] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.686972] NET: Registered protocol family 1 [ 0.688441] RPC: Registered named UNIX socket transport module. [ 0.689403] RPC: Registered udp transport module. [ 0.690360] RPC: Registered tcp transport module. [ 0.691331] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.692553] NET: Registered protocol family 44 [ 0.693409] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.694264] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.694979] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.696665] PCI: CLS 0 bytes, default 64 [ 0.697841] Unpacking initramfs... [ 2.138922] debug: unmapping init [mem 0xffff8dfe3cc64000-0xffff8dfe3ffcffff] [ 2.143065] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.145508] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.149739] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.667279] Initialise system trusted keyrings [ 2.668925] Key type blacklist registered [ 2.670917] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.680400] zbud: loaded [ 2.683566] *** VALIDATE nfs *** [ 2.684833] *** VALIDATE nfs4 *** [ 2.686411] pstore: using deflate compression [ 2.690152] Platform Keyring initialized [ 2.772118] NET: Registered protocol family 38 [ 2.773420] Key type asymmetric registered [ 2.774472] Asymmetric key parser 'x509' registered [ 2.776109] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.777933] io scheduler mq-deadline registered [ 2.778907] io scheduler kyber registered [ 2.779967] io scheduler bfq registered [ 2.781323] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.783242] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.784813] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.786620] ACPI: Power Button [PWRF] [ 2.789939] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.795093] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.800859] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.826648] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.853277] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.857923] Non-volatile memory driver v1.3 [ 2.859686] Linux agpgart interface v0.103 [ 2.888667] virtio_blk virtio1: [vda] 134736 512-byte logical blocks (69.0 MB/65.8 MiB) [ 2.890647] vda: detected capacity change from 0 to 68984832 [ 2.901667] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.904231] vdb: detected capacity change from 0 to 1073741824 [ 2.909726] libphy: Fixed MDIO Bus: probed [ 2.918892] usbcore: registered new interface driver usbserial_generic [ 2.921765] usbserial: USB Serial support registered for generic [ 2.924401] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.928914] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.930918] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.934478] mousedev: PS/2 mouse device common for all mice [ 2.937787] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.939544] rtc_cmos 00:05: RTC can wake from S4 [ 2.947737] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.948020] rtc_cmos 00:05: registered as rtc0 [ 2.953956] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.956576] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.957759] intel_pstate: CPU model not supported [ 2.963274] hid: raw HID events driver (C) Jiri Kosina [ 2.965299] usbcore: registered new interface driver usbhid [ 2.967192] usbhid: USB HID core driver [ 2.969110] drop_monitor: Initializing network drop monitor service [ 2.971425] Initializing XFRM netlink socket [ 2.973544] NET: Registered protocol family 10 [ 2.976232] Segment Routing with IPv6 [ 2.977177] NET: Registered protocol family 17 [ 2.978669] mpls_gso: MPLS GSO support [ 2.984243] RAS: Correctable Errors collector initialized. [ 2.985563] AVX version of gcm_enc/dec engaged. [ 2.986591] AES CTR mode by8 optimization enabled [ 3.048182] sched_clock: Marking stable (3048158135, 0)->(3920084733, -871926598) [ 3.051977] registered taskstats version 1 [ 3.054301] Loading compiled-in X.509 certificates [ 3.056305] zswap: loaded using pool lzo/zbud [ 3.081031] Key type big_key registered [ 3.092762] Key type encrypted registered [ 3.094264] ima: No TPM chip found, activating TPM-bypass! [ 3.098040] ima: Allocated hash algorithm: sha1 [ 3.099590] ima: No architecture policies found [ 3.100923] evm: Initialising EVM extended attributes: [ 3.102142] evm: security.selinux [ 3.103365] evm: security.ima [ 3.104363] evm: security.capability [ 3.105625] evm: HMAC attrs: 0x1 [ 3.107929] rtc_cmos 00:05: setting system clock to 2026-02-27 06:59:48 UTC (1772175588) [ 3.113911] debug: unmapping init [mem 0xffffffff8de03000-0xffffffff8dffffff] [ 3.116599] debug: unmapping init [mem 0xffffffff8cb82000-0xffffffff8ce58fff] [ 3.125240] Write protecting the kernel read-only data: 28672k [ 3.128212] debug: unmapping init [mem 0xffffffff8b203000-0xffffffff8b3fffff] [ 3.130962] debug: unmapping init [mem 0xffffffff8bb14000-0xffffffff8bbfffff] [ 3.163929] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.170880] systemd[1]: Detected virtualization kvm. [ 3.172468] systemd[1]: Detected architecture x86-64. [ 3.174438] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.202062] systemd[1]: No hostname configured. [ 3.203127] systemd[1]: Set hostname to . [ 3.204465] random: systemd: uninitialized urandom read (16 bytes read) [ 3.205909] systemd[1]: Initializing machine ID from random generator. [ 3.236576] random: ln: uninitialized urandom read (6 bytes read) [ 3.331164] random: systemd: uninitialized urandom read (16 bytes read) [ 3.333372] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.337267] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.341155] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Timers. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.875287] device-mapper: uevent: version 1.0.3 [ 3.877506] 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... [ 4.530789] virtio_net virtio0 ens2: renamed from eth0 [ 4.612538] scsi host0: ata_piix [ 4.638299] scsi host1: ata_piix [ 4.655921] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.658098] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.330726] dracut-initqueue[ 8.331953] random: fast init done [581]: RTNETLINK answers: File exists [ 9.619113] random: crng init done [ 9.620417] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 9.958768] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ 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... [ 11.065448] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.316317] SELinux: Disabled at runtime. [ 11.372385] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.379062] systemd[1]: Detected virtualization kvm. [ 11.380110] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.896441] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.899435] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.907586] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.911149] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.913399] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.920248] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.939476] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Switch Root. Starting Create list of required st…ce nodes for the current kernel... Starting Remount Root and Kernel File Systems... Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK [0[ 12.066587] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS m] Stopped target Initrd Root File System. Mounting Kernel Debug File System... [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ 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 ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.410755] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.751074] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.758235] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.912730] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.920779] EDAC sbridge: Ver: 1.1.2 [ 14.046054] Key type dns_resolver registered [ 14.348606] NFS: Registering the id_resolver key type [ 14.352281] Key type id_resolver registered [ 14.353752] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ 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... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ 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 Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg301-client login: [ 49.849685] libcfs: loading out-of-tree module taints kernel. [ 50.358354] Key type ._llcrypt registered [ 50.360418] Key type .llcrypt registered [ 50.684235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 50.700555] alg: No test for adler32 (adler32-zlib) [ 52.095352] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 52.944517] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 54.703364] Key type lgssc registered [ 56.610034] Lustre: Echo OBD driver; http://www.lustre.org/ [ 61.353008] hrtimer: interrupt took 1999351 ns [ 167.794979] Lustre: Mounted lustre-client [ 172.068991] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 188.971526] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing check_logdir /tmp/testlogs/ [ 193.510148] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 23s idle [ 194.171314] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing yml_node [ 197.940409] Lustre: DEBUG MARKER: Client: 2.17.50.152 [ 200.723947] Lustre: DEBUG MARKER: MDS: 2.17.50.152 [ 203.146404] Lustre: DEBUG MARKER: OSS: 2.17.50.152 [ 204.704869] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Feb 27 02:03:08 EST 2026 [ 218.591246] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 219.853580] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 221.242361] Lustre: DEBUG MARKER: === sanityn: start setup 02:03:25 (1772175805) === [ 221.809176] Lustre: Mounted lustre-client [ 224.884611] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing check_config_client /mnt/lustre [ 239.937304] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 242.655392] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: disconnect after 21s idle [ 242.663636] Lustre: Skipped 1 previous similar message [ 249.266322] Lustre: DEBUG MARKER: === sanityn: finish setup 02:03:53 (1772175833) === [ 251.142898] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 02:03:55 (1772175835) [ 258.120274] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 02:04:02 (1772175842) [ 263.647921] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 02:04:07 (1772175847) [ 268.814317] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 02:04:13 (1772175853) [ 274.320195] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 02:04:18 (1772175858) [ 280.119936] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 02:04:24 (1772175864) [ 285.643849] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 02:04:29 (1772175869) [ 290.938807] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 02:04:35 (1772175875) [ 292.362661] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 293.707654] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 02:04:38 (1772175878) [ 299.552869] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 02:04:43 (1772175883) [ 304.805390] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 02:04:49 (1772175889) [ 310.771766] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 02:04:55 (1772175895) [ 314.340427] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 20s idle [ 314.344034] Lustre: Skipped 1 previous similar message [ 316.190105] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 02:05:00 (1772175900) [ 321.691923] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 02:05:05 (1772175905) [ 326.920084] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 02:05:11 (1772175911) [ 329.695341] Lustre: lustre-OST0001-osc-ffff8dfe98a8b000: disconnect after 24s idle [ 332.181826] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 02:05:16 (1772175916) [ 338.423592] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 02:05:22 (1772175922) [ 344.164930] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 02:05:28 (1772175928) [ 349.606808] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 02:05:33 (1772175933) [ 354.191418] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 02:05:38 (1772175938) [ 358.749712] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 02:05:43 (1772175943) [ 359.341513] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 501.986455] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 02:08:06 (1772176086) [ 507.745048] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 02:08:12 (1772176092) [ 512.022750] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 02:08:16 (1772176096) [ 516.367159] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 02:08:20 (1772176100) [ 520.799831] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 02:08:25 (1772176105) [ 525.061151] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 02:08:29 (1772176109) [ 526.373925] Lustre: DEBUG MARKER: chmod [ 530.831636] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 02:08:35 (1772176115) [ 557.842375] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7526400KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 569.044913] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 02:09:13 (1772176153) [ 603.152497] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 02:09:47 (1772176187) [ 623.787930] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 02:10:08 (1772176208) [ 625.648974] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 626.888715] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 02:10:11 (1772176211) [ 658.011874] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 02:10:42 (1772176242) [ 662.502531] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 02:10:47 (1772176247) [ 663.284680] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.349347] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.398855] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.439221] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.482564] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.526679] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.584601] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.639893] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.706883] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.758031] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.802419] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.850907] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.897091] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.933182] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 663.968791] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.003429] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.057444] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.096735] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.158057] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.201655] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.239940] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.278616] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.322127] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.378580] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.423119] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.464988] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.509798] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.552678] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.600441] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.660729] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.713521] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.769626] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.821342] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.875391] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 664.960329] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.013223] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.077708] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.136923] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.211418] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.266993] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.314730] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.365329] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.415164] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.470719] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.516251] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.583856] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.633750] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.673674] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.718686] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.765707] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.818988] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.885865] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.926671] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 665.966875] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.014275] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.070686] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.151291] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.202780] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.248829] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.289561] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.328106] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.363355] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.401054] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.440637] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.473599] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.503887] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.540859] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.574533] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.614468] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.648882] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.682950] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.720833] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.754369] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.797208] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.847274] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.887052] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.921775] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.958517] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 666.994061] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.027631] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.065723] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.139792] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.187337] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.245312] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.289654] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.336364] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.392725] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.448325] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.506520] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.604668] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.656903] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.692722] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.748639] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.796369] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.830650] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.879414] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.927568] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 667.983515] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.031658] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.074841] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.133340] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.180985] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.240735] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.292886] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.350040] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.401238] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.441215] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.479468] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.526470] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.564141] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.611342] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.661783] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.697368] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.740921] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.784516] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.829111] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.868159] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.907973] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.941934] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 668.978073] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.017329] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.058127] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.095378] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.147671] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.199287] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.249871] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.297808] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.342081] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.398979] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.446501] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.524555] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.593284] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.635403] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.695617] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.767477] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.833674] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.886735] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.932708] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 669.990579] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.033507] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.077131] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.122726] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.172451] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.226859] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.287893] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.331542] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.390505] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.428928] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.489274] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.533636] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.580652] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.619942] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.652857] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.686807] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.720610] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.753232] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.784181] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.810846] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.845829] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.881342] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.920204] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.953672] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 670.993607] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.039663] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.084925] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.124035] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.161938] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.198161] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.252066] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.291231] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.339035] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.397839] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.435695] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.488175] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.529577] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.584205] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.630083] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.690736] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.748900] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.783204] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.822969] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.858542] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.907528] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.941503] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 671.987807] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.020255] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.054957] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.096509] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.145099] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.193393] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.231118] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.288756] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.342203] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.396688] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.428698] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.461718] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.490095] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.523002] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.565215] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.597791] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.636321] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.685949] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.730251] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.775040] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.842815] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 672.926724] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.006549] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.066308] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.108921] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.155375] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.197742] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.227521] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.270037] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.331722] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.411094] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.457925] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.504720] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.566496] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.626800] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.696185] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.736287] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.767769] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.805678] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.844400] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.884152] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.917473] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.947688] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 673.981943] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.016670] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.050201] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.117838] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.182745] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.233793] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.285386] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.351957] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.409565] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.457372] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.496730] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.550288] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.613520] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.712993] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.792295] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.868738] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.914752] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 674.956075] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.003750] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.063730] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.107559] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.176499] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.266289] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.317507] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.359624] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.413269] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.447197] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.488577] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.532421] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.585092] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.635154] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.675117] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.713941] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.771660] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.835143] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.886490] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.942310] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 675.990099] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.043914] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.110674] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.193320] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.286466] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.342780] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.398190] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.460669] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.520327] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.577494] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.628623] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.684883] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.761847] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.820526] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.877663] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.932952] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 676.984461] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.040597] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.092630] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.161190] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.216709] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.267535] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.330553] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.384527] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.435774] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.489265] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.552575] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.599568] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.639743] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.680040] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.757568] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.805237] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.842836] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.886790] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.935765] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 677.999592] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.055600] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.110607] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.170064] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.224093] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.304262] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.362844] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.435051] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.469221] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.532656] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.586815] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.637892] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.691766] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.749110] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.789791] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.836964] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.884702] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.946501] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 678.988672] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.031436] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.103593] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.155508] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.194989] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.226680] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.265133] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.303987] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.358249] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.425258] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.469037] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.511145] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.548354] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.590041] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.626529] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.666985] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.706430] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.755714] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.789049] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.822084] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.860583] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.900391] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.944287] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 679.979906] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.011821] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.052332] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.093169] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.147374] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.196214] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.248431] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.294282] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.368508] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.431450] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.496307] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.551559] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.608549] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.644540] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.685585] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.723946] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.763847] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.799207] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.829866] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.874861] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.924408] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.951588] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 680.991930] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.028689] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.069377] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.104309] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.135406] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.172237] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.204898] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.239167] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.289599] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.328923] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.381410] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.418895] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.458552] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.499300] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.535752] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.575914] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.614988] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.657718] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.701122] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.770523] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.826414] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.875881] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.903105] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 681.959949] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.002615] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.051482] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.085312] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.132341] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.162940] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.211390] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.261319] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.331723] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.365122] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.402835] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.452810] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.515093] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.564985] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.619679] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.662178] rw_seq_cst_vs_d (29467): drop_caches: 3 [ 682.976274] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 20s idle [ 687.035877] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 02:11:11 (1772176271) [ 687.298784] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 687.424338] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 687.491498] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.108155] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.162400] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.209852] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.286689] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.317729] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.437744] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.658977] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.710152] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.808131] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.875568] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.898835] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.925719] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 688.956710] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.043789] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.161936] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.254276] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.544312] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.765515] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.808173] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 689.915503] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.099533] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.296735] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.384052] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.428943] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.517415] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.651209] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.814473] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.841872] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.886904] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.908572] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 690.953278] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.075101] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.114192] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.153100] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.186200] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.210028] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.252176] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.311201] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.347603] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.405936] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.482535] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.551696] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.578838] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.778023] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.809896] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.850395] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 691.982576] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.143080] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.167992] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.214600] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.246545] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.358879] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.427309] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.469410] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.539801] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.580459] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.624207] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.658844] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.811707] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 692.866743] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.039353] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.084402] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.110741] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.146945] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.196536] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.259889] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.283334] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.377984] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.607244] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.645791] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.670452] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 693.898419] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.106163] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.201624] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.292135] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.516551] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.572304] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.616954] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.654025] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.757336] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 694.903111] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.078425] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.107581] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.135636] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.237124] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.269634] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.294545] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.410214] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.452498] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.526993] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.558612] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.596748] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.626661] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.662212] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.680730] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.762316] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.826069] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.848987] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 695.907713] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.006324] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.090899] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.148347] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.174306] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.197736] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.237304] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.268474] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.358730] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.420353] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.446901] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.486697] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.529280] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.595169] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.663487] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.739800] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.804164] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 696.995533] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.185412] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.393081] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.499328] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.535027] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.565505] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.759861] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.837861] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 697.931877] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.051841] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.086944] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.186962] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.241652] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.321741] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.477577] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.554544] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.737613] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 698.877542] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.019980] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.123461] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.229131] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.520886] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.557043] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.855166] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 699.932634] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.002149] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.126150] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.182307] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.231125] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.333456] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.546169] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.614855] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.645693] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.676602] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 700.701767] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.024484] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.118792] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.186753] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.262895] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.359792] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.664797] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.777486] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.862139] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 701.890692] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.116542] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.259873] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.320512] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.457168] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.481748] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.583854] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.621684] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.793213] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 702.987614] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.054506] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.120714] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.322760] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.429758] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.455290] Lustre: lustre-OST0001-osc-ffff8dfe98a78000: disconnect after 20s idle [ 703.457651] Lustre: Skipped 1 previous similar message [ 703.535607] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.565336] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.664897] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.712522] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.770185] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.916299] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.961767] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 703.992758] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.057260] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.257041] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.293171] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.396652] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.503019] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.556995] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.591028] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.648271] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.752739] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.850850] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.876688] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 704.958860] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.062980] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.193548] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.287264] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.332475] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.378832] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.467827] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.576940] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.620179] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.662908] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.736907] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.917676] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.943276] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 705.996466] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.070310] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.240859] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.312981] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.466285] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.608464] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.667954] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.830771] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.894082] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 706.961898] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 707.058756] rw_seq_cst_vs_d (30040): drop_caches: 3 [ 712.311793] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 02:11:36 (1772176296) [ 717.256396] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 02:11:41 (1772176301) [ 721.726880] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 02:11:46 (1772176306) [ 745.435280] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 02:12:09 (1772176329) [ 745.737550] bash (32488): drop_caches: 3 [ 748.880760] bash (32488): drop_caches: 3 [ 752.300233] bash (32488): drop_caches: 3 [ 755.423160] bash (32488): drop_caches: 3 [ 758.511812] bash (32488): drop_caches: 3 [ 761.615971] bash (32488): drop_caches: 3 [ 764.700449] bash (32488): drop_caches: 3 [ 767.803442] bash (32488): drop_caches: 3 [ 770.876279] bash (32488): drop_caches: 3 [ 773.977759] bash (32488): drop_caches: 3 [ 778.261223] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 02:12:42 (1772176362) [ 785.245188] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 02:12:49 (1772176369) [ 804.472791] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 02:13:09 (1772176389) [ 806.185933] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 807.254889] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 02:13:11 (1772176391) [ 811.311658] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 02:13:15 (1772176395) [ 815.052229] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 02:13:19 (1772176399) [ 821.215210] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: disconnect after 23s idle [ 821.218033] Lustre: Skipped 1 previous similar message [ 880.696837] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 02:14:25 (1772176465) [ 884.824168] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 02:14:29 (1772176469) [ 888.848653] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 02:14:33 (1772176473) [ 893.112786] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 02:14:37 (1772176477) [ 893.911634] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 894.913661] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 02:14:39 (1772176479) [ 900.299108] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 02:14:44 (1772176484) [ 906.062921] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 02:14:50 (1772176490) [ 910.980920] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 912.066353] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 02:14:56 (1772176496) [ 917.786325] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 02:15:02 (1772176502) [ 917.963658] Lustre: *** cfs_fail_loc=314, val=0*** [ 919.007178] Lustre: *** cfs_fail_loc=314, val=0*** [ 919.012111] Lustre: Skipped 2 previous similar messages [ 922.394140] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 02:15:07 (1772176507) [ 932.697601] Lustre: *** cfs_fail_loc=314, val=0*** [ 933.867369] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 933.881274] LustreError: lustre-OST0000-osc-ffff8dfe98a8b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 933.890715] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 936.420895] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 02:15:21 (1772176521) [ 936.548109] LustreError: 42304:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 939.567329] LustreError: 42304:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 942.602478] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 02:15:27 (1772176527) [ 946.402790] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 02:15:31 (1772176531) [ 951.162758] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 952.110585] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 02:15:36 (1772176536) [ 953.164310] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 954.149917] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 02:15:38 (1772176538) [ 955.321835] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 956.370862] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 02:15:40 (1772176540) [ 957.382338] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 958.393199] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 02:15:42 (1772176542) [ 959.244601] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 960.277767] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 02:15:44 (1772176544) [ 1014.722440] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1014.737333] Lustre: Skipped 1 previous similar message [ 1014.754772] LustreError: lustre-OST0000-osc-ffff8dfe98a78000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1014.761800] LustreError: lustre-OST0000-osc-ffff8dfe98a8b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1014.768662] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1014.774734] Lustre: Skipped 1 previous similar message [ 1030.087463] Lustre: lustre-OST0001-osc-ffff8dfe98a78000: Connection to lustre-OST0001 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1030.101975] LustreError: lustre-OST0001-osc-ffff8dfe98a78000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1030.110356] Lustre: lustre-OST0001-osc-ffff8dfe98a78000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 1036.256969] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 21s idle [ 1036.267648] Lustre: Skipped 3 previous similar messages [ 1043.030693] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1043.700943] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid in IDLE state after 0 sec [ 1045.482132] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1046.285366] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid in FULL state after 0 sec [ 1048.848657] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1049.720529] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid in IDLE state after 0 sec [ 1051.561785] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1052.391071] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid in IDLE state after 0 sec [ 1057.932581] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1058.895431] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8dfe98a78000.ost_server_uuid in IDLE state after 0 sec [ 1061.408446] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid 50 [ 1062.266487] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8dfe98a78000.ost_server_uuid in IDLE state after 0 sec [ 1063.209374] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 02:17:27 (1772176647) [ 1064.831645] Lustre: DEBUG MARKER: Race attempt 0 [ 1066.903080] Lustre: DEBUG MARKER: Wait for 48931 49016 for 60 sec... [ 1130.173391] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 02:18:34 (1772176714) [ 1136.405425] Lustre: DEBUG MARKER: start test - cycle (0) [ 1158.343997] Lustre: DEBUG MARKER: start test - cycle (1) [ 1178.903629] Lustre: DEBUG MARKER: start test - cycle (2) [ 1199.679490] Lustre: DEBUG MARKER: start test - cycle (3) [ 1220.025877] Lustre: DEBUG MARKER: start test - cycle (4) [ 1241.113863] Lustre: DEBUG MARKER: start test - cycle (5) [ 1260.646939] Lustre: DEBUG MARKER: start test - cycle (6) [ 1281.650354] Lustre: DEBUG MARKER: start test - cycle (7) [ 1302.448511] Lustre: DEBUG MARKER: start test - cycle (8) [ 1323.348247] Lustre: DEBUG MARKER: start test - cycle (9) [ 1343.024227] Lustre: DEBUG MARKER: start test - cycle (10) [ 1367.558874] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 02:22:32 (1772176952) [ 1374.178771] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: disconnect after 24s idle [ 1374.183252] Lustre: Skipped 2 previous similar messages [ 1412.621521] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 02:23:17 (1772176997) [ 1415.903267] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 02:23:20 (1772177000) [ 1420.060192] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 02:23:24 (1772177004) [ 1424.221652] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 02:23:28 (1772177008) [ 1424.388184] Lustre: *** cfs_fail_loc=411, val=0*** [ 1427.291212] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1428.136791] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 02:23:32 (1772177012) [ 1438.510385] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 02:23:43 (1772177023) [ 1448.283936] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 02:23:53 (1772177033) [ 1457.726287] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 02:24:02 (1772177042) [ 1466.525852] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 02:24:11 (1772177051) [ 1473.537826] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 02:24:18 (1772177058) [ 1481.019982] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 02:24:25 (1772177065) [ 1488.762049] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 02:24:33 (1772177073) [ 1496.328182] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 02:24:40 (1772177080) [ 1503.603730] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 02:24:48 (1772177088) [ 1510.260869] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 02:24:55 (1772177095) [ 1512.416484] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 23s idle [ 1512.419469] Lustre: Skipped 4 previous similar messages [ 1517.492138] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 02:25:02 (1772177102) [ 1525.108550] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 02:25:09 (1772177109) [ 2147.295246] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: disconnect after 24s idle [ 2147.302238] Lustre: Skipped 2 previous similar messages [ 2263.480946] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 02:37:28 (1772177848) [ 2269.320924] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 02:37:34 (1772177854) [ 2275.313122] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 02:37:40 (1772177860) [ 2281.399546] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 02:37:46 (1772177866) [ 2287.230747] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 02:37:52 (1772177872) [ 2293.277425] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 02:37:58 (1772177878) [ 2299.226889] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 02:38:04 (1772177884) [ 2305.145151] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 02:38:09 (1772177889) [ 2311.222840] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 02:38:16 (1772177896) [ 2336.205717] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 02:38:41 (1772177921) [ 2341.934299] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 02:38:46 (1772177926) [ 2347.747360] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 02:38:52 (1772177932) [ 2353.431685] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 02:38:58 (1772177938) [ 2359.254408] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 02:39:04 (1772177944) [ 2364.996338] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 02:39:09 (1772177949) [ 2370.578593] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 02:39:15 (1772177955) [ 2375.869360] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 02:39:20 (1772177960) [ 2376.404648] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2377.006277] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 02:39:21 (1772177961) [ 2419.729367] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 02:40:04 (1772178004) [ 2845.726371] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 02:47:10 (1772178430) [ 2850.910743] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 02:47:15 (1772178435) [ 2856.075750] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 02:47:21 (1772178441) [ 2861.344824] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 02:47:26 (1772178446) [ 2866.664408] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 02:47:31 (1772178451) [ 2871.944854] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 02:47:36 (1772178456) [ 2877.181338] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 02:47:42 (1772178462) [ 2882.318106] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 02:47:47 (1772178467) [ 2887.613163] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 02:47:52 (1772178472) [ 2888.104625] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2888.705193] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 02:47:53 (1772178473) [ 2905.055172] Lustre: lustre-OST0000-osc-ffff8dfe98a78000: disconnect after 23s idle [ 2905.057078] Lustre: Skipped 6 previous similar messages [ 2918.048662] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 02:48:23 (1772178503) [ 2923.080045] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 02:48:28 (1772178508) [ 2928.068959] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 02:48:33 (1772178513) [ 2933.216822] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 02:48:38 (1772178518) [ 2938.520925] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 02:48:43 (1772178523) [ 2943.802440] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 02:48:48 (1772178528) [ 2949.052752] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 02:48:53 (1772178533) [ 2953.584272] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 02:48:58 (1772178538) [ 2954.069561] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2954.597526] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 02:48:59 (1772178539) [ 3377.560661] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 02:56:02 (1772178962) [ 3382.387015] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 02:56:07 (1772178967) [ 3387.385375] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 02:56:12 (1772178972) [ 3392.235956] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 02:56:17 (1772178977) [ 3397.245941] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 02:56:22 (1772178982) [ 3402.425341] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 02:56:27 (1772178987) [ 3407.736191] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 02:56:32 (1772178992) [ 3412.541720] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 02:56:37 (1772178997) [ 3417.669785] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 02:56:42 (1772179002) [ 3418.101237] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3418.601275] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 02:56:43 (1772179003) [ 3419.043169] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3419.534687] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 02:56:44 (1772179004) [ 3420.008390] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3420.555533] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 02:56:45 (1772179005) [ 3421.058126] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3421.588540] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 02:56:46 (1772179006) [ 3422.082750] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3422.653227] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 02:56:47 (1772179007) [ 3423.161484] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3423.780836] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 02:56:48 (1772179008) [ 3424.372204] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3425.041724] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 02:56:49 (1772179009) [ 3425.625825] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3426.191774] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 02:56:51 (1772179011) [ 3426.271920] LustreError: 5554:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3428.351109] LustreError: 5554:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3433.481594] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 02:56:58 (1772179018) [ 3437.518917] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 02:57:02 (1772179022) [ 3437.602742] LustreError: 216402:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3441.663106] LustreError: 216402:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3441.672043] LustreError: 216402:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3445.727068] LustreError: 216402:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3445.739251] LustreError: 216409:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3449.799108] LustreError: 216409:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3451.886680] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 02:57:16 (1772179036) [ 3458.652831] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 02:57:23 (1772179043) [ 3461.808777] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 02:57:26 (1772179046) [ 3465.908564] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 02:57:30 (1772179050) [ 3490.361517] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 02:57:55 (1772179075) [ 3498.287667] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 02:58:03 (1772179083) [ 3506.060729] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 02:58:10 (1772179090) [ 3519.057467] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 02:58:23 (1772179103) [ 3528.617809] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 02:58:33 (1772179113) [ 3529.158993] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3529.719203] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 02:58:34 (1772179114) [ 3542.754107] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 02:58:47 (1772179127) [ 3556.953458] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 02:59:01 (1772179141) [ 3589.879532] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 02:59:34 (1772179174) [ 3631.839425] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 03:00:16 (1772179216) [ 3634.376821] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3636.758817] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 03:00:21 (1772179221) [ 3639.280404] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 03:00:24 (1772179224) [ 3639.990852] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3640.741687] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 03:00:25 (1772179225) [ 3641.633576] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3642.436229] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 03:00:27 (1772179227) [ 3643.212144] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3644.028046] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 03:00:28 (1772179228) [ 3644.823238] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3645.691913] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 03:00:30 (1772179230) [ 3648.219686] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 03:00:33 (1772179233) [ 3650.588147] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 03:00:35 (1772179235) [ 3653.663762] LustreError: lustre-MDT0000-mdc-ffff8dfe98a78000: operation ldlm_enqueue to node 192.168.203.101@tcp failed: rc = -35 [ 3653.666942] LustreError: Skipped 1 previous similar message [ 3656.784239] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 03:00:41 (1772179241) [ 3656.948067] LustreError: 2365:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3659.031105] LustreError: 2365:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3664.009424] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 03:00:48 (1772179248) [ 3685.742609] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 03:01:10 (1772179270) [ 3688.932221] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 03:01:13 (1772179273) [ 3693.090403] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 03:01:17 (1772179277) [ 3698.176626] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 03:01:23 (1772179283) [ 3703.265974] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 03:01:28 (1772179288) [ 3708.895164] Lustre: lustre-OST0000-osc-ffff8dfe98a8b000: disconnect after 23s idle [ 3708.898373] Lustre: Skipped 3 previous similar messages [ 3711.453106] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 03:01:36 (1772179296) [ 3719.188623] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 03:01:44 (1772179304) [ 3722.546621] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 03:01:47 (1772179307) [ 3726.396557] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 03:01:51 (1772179311) [ 3734.126470] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 03:01:59 (1772179319) [ 3785.270281] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 03:02:50 (1772179370) [ 3917.005716] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 03:05:01 (1772179501) [ 4049.446972] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 03:07:14 (1772179634) [ 4348.895199] Lustre: lustre-OST0001-osc-ffff8dfe98a78000: disconnect after 24s idle [ 4348.897172] Lustre: Skipped 15 previous similar messages [ 4400.116568] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 03:13:05 (1772179985) [ 4437.375658] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 03:13:42 (1772180022) [ 4476.702413] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 03:14:21 (1772180061) [ 4509.589694] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 03:14:54 (1772180094) [ 4603.792039] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 03:16:27 (1772180187) [ 4674.080198] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 03:17:38 (1772180258) [ 4801.993036] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 03:19:46 (1772180386) [ 4805.057402] LustreError: 257317:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe98a78000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4805.084741] Lustre: Unmounted lustre-client [ 4808.065691] LustreError: 257330:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe98a8b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4808.070302] LustreError: 257330:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4808.094375] Lustre: Unmounted lustre-client [ 4888.748208] Lustre: Mounted lustre-client [ 4891.089819] Lustre: Mounted lustre-client [ 4892.960713] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 4983.263716] Lustre: lustre-OST0000-osc-ffff8dfeafc4e000: disconnect after 24s idle [ 4983.274865] Lustre: Skipped 18 previous similar messages [ 4991.039608] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 03:22:55 (1772180575) [ 5002.772551] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 03:23:07 (1772180587) [ 5009.035641] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 03:23:13 (1772180593) [ 5064.031747] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 03:24:08 (1772180648) [ 5141.042767] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 03:25:25 (1772180725) [ 5147.877438] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 03:25:32 (1772180732) [ 5211.242425] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 03:26:35 (1772180795) [ 5228.419645] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 03:26:53 (1772180813) [ 5271.209808] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 03:27:36 (1772180856) [ 5274.834494] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 03:27:39 (1772180859) [ 5288.797496] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 03:27:53 (1772180873) [ 5289.532764] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5290.391275] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 03:27:55 (1772180875) [ 5291.208838] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5292.076595] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 03:27:56 (1772180876) [ 5292.965532] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5293.979926] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 03:27:58 (1772180878) [ 5294.878456] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5296.043234] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 03:28:00 (1772180880) [ 5296.989156] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5297.902907] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 03:28:02 (1772180882) [ 5368.321588] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 03:29:12 (1772180952) [ 5372.310556] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 03:29:16 (1772180956) [ 5373.206344] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5374.185261] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 03:29:18 (1772180958) [ 5383.577533] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 03:29:28 (1772180968) [ 5388.789279] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 03:29:33 (1772180973) [ 5389.688228] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5390.512081] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 03:29:35 (1772180975) [ 5391.293198] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5392.149990] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 03:29:36 (1772180976) [ 5393.048483] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5393.990160] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 03:29:38 (1772180978) [ 5404.621163] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 03:29:49 (1772180989) [ 5404.770868] Lustre: DEBUG MARKER: write [ 5404.792276] LustreError: 259411:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5406.798865] Lustre: DEBUG MARKER: kill 284443 [ 5406.801270] LustreError: 284443:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5409.895108] LustreError: 259411:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5412.839419] LustreError: 284443:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5415.470818] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 03:30:00 (1772181000) [ 5417.670247] LustreError: 285048:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5427.767122] LustreError: 285048:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5431.352576] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 03:30:15 (1772181015) [ 5431.608309] LustreError: 285628:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5433.695118] LustreError: 285628:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [ 5440.933956] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 03:30:25 (1772181025) [ 5441.635565] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5442.525532] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 03:30:27 (1772181027) [ 5446.013370] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 03:30:30 (1772181030) [ 5449.432989] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 03:30:34 (1772181034) [ 5452.321227] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 03:30:37 (1772181037) [ 5455.347688] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 03:30:40 (1772181040) [ 5458.245072] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 03:30:43 (1772181043) [ 5461.044193] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 03:30:45 (1772181045) [ 5464.664621] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5465.301938] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 03:30:50 (1772181050) [ 5466.011971] Lustre: *** cfs_fail_loc=415, val=0*** [ 5472.996193] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 03:30:57 (1772181057) [ 5473.594403] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5474.230712] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 03:30:59 (1772181059) [ 5474.321777] LustreError: 263311:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5474.326949] LustreError: 263311:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 5479.327118] LustreError: 263311:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5489.527118] LustreError: 263311:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5489.530894] LustreError: 263311:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 5498.025379] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 03:31:22 (1772181082) [ 5498.776407] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5499.660788] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 03:31:24 (1772181084) [ 5503.551720] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 03:31:28 (1772181088) [ 5507.551607] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 03:31:32 (1772181092) [ 5513.781237] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 03:31:38 (1772181098) [ 5522.839322] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 03:31:47 (1772181107) [ 5523.014436] LustreError: 294003:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5523.017392] LustreError: 294003:0:(osc_request.c:2968:osc_build_rpc()) Skipped 5 previous similar messages [ 5527.079105] LustreError: 294003:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 5527.082527] LustreError: 294003:0:(osc_request.c:2968:osc_build_rpc()) Skipped 2 previous similar messages [ 5529.460825] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 03:31:54 (1772181114) [ 5530.214719] LustreError: 295573:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeafc4e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5530.218781] LustreError: 295573:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5530.239096] Lustre: Unmounted lustre-client [ 5531.048802] Lustre: DEBUG MARKER: Iteration 1 [ 5531.141788] LustreError: 295753:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5531.143238] LustreError: 295754:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5531.146869] LustreError: 295753:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5531.201588] Lustre: Mounted lustre-client [ 5531.628531] LustreError: 295853:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe98817000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5531.631784] LustreError: 295853:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 5531.671079] Lustre: Unmounted lustre-client [ 5531.672285] Lustre: Skipped 1 previous similar message [ 5532.624476] Key type lgssc unregistered [ 5532.752493] LNet: 296099:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5532.755977] LNetError: 296099:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5532.764136] LNet: Removed LNI 192.168.203.1@tcp [ 5533.050117] Key type .llcrypt unregistered [ 5533.051382] Key type ._llcrypt unregistered [ 5533.336929] Key type ._llcrypt registered [ 5533.338213] Key type .llcrypt registered [ 5533.588247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5533.595147] alg: No test for adler32 (adler32-zlib) [ 5534.584965] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5534.915634] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5536.527144] Key type lgssc registered [ 5537.269846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5543.443217] Lustre: DEBUG MARKER: Iteration 2 [ 5543.569469] LustreError: 296919:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5543.569501] LustreError: 296921:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5543.575698] LustreError: 296919:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5544.678545] Lustre: Mounted lustre-client [ 5545.262393] LustreError: 297025:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe87154800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5545.295257] Lustre: Unmounted lustre-client [ 5546.578704] Key type lgssc unregistered [ 5546.720531] LNet: 297268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5546.723624] LNetError: 297268:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5546.734554] LNet: Removed LNI 192.168.203.1@tcp [ 5547.035120] Key type .llcrypt unregistered [ 5547.036364] Key type ._llcrypt unregistered [ 5547.375157] Key type ._llcrypt registered [ 5547.376515] Key type .llcrypt registered [ 5547.601666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5547.607558] alg: No test for adler32 (adler32-zlib) [ 5548.527026] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5548.660734] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5550.295141] Key type lgssc registered [ 5550.771144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5555.447782] Lustre: DEBUG MARKER: Iteration 3 [ 5555.569838] LustreError: 298089:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5555.570948] LustreError: 298090:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5555.576934] LustreError: 298089:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5556.661997] Lustre: Mounted lustre-client [ 5557.602715] LustreError: 298191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89270000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5557.639775] Lustre: Unmounted lustre-client [ 5558.897961] Key type lgssc unregistered [ 5559.022403] LNet: 298441:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5559.025971] LNetError: 298441:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5559.032621] LNet: Removed LNI 192.168.203.1@tcp [ 5559.292217] Key type .llcrypt unregistered [ 5559.293744] Key type ._llcrypt unregistered [ 5559.698607] Key type ._llcrypt registered [ 5559.699914] Key type .llcrypt registered [ 5559.817126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5559.823867] alg: No test for adler32 (adler32-zlib) [ 5560.693479] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5560.798905] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5562.391160] Key type lgssc registered [ 5562.897552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5567.140108] Lustre: DEBUG MARKER: Iteration 4 [ 5567.295909] LustreError: 299261:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5567.296532] LustreError: 299262:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5567.303022] LustreError: 299261:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5568.392319] Lustre: Mounted lustre-client [ 5569.016049] LustreError: 299361:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe88703000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5569.047900] Lustre: Unmounted lustre-client [ 5570.504717] Key type lgssc unregistered [ 5570.660958] LNet: 299611:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5570.665082] LNetError: 299611:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5570.676810] LNet: Removed LNI 192.168.203.1@tcp [ 5571.078810] Key type .llcrypt unregistered [ 5571.080251] Key type ._llcrypt unregistered [ 5571.678849] Key type ._llcrypt registered [ 5571.684368] Key type .llcrypt registered [ 5571.790882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5571.807325] alg: No test for adler32 (adler32-zlib) [ 5572.718516] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5572.848160] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5574.463694] Key type lgssc registered [ 5574.992975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5579.348425] Lustre: DEBUG MARKER: Iteration 5 [ 5579.452130] LustreError: 300431:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5579.452389] LustreError: 300432:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5579.457567] LustreError: 300431:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5580.550882] Lustre: Mounted lustre-client [ 5581.023192] LustreError: 300541:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe98a72000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5581.027155] LustreError: 300541:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5581.051349] Lustre: Unmounted lustre-client [ 5582.257617] Key type lgssc unregistered [ 5582.379607] LNet: 300783:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5582.385865] LNetError: 300783:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5582.398728] LNet: Removed LNI 192.168.203.1@tcp [ 5582.691126] Key type .llcrypt unregistered [ 5582.693695] Key type ._llcrypt unregistered [ 5583.028249] Key type ._llcrypt registered [ 5583.029778] Key type .llcrypt registered [ 5583.181461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5583.187062] alg: No test for adler32 (adler32-zlib) [ 5584.078896] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5584.202088] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5585.791183] Key type lgssc registered [ 5586.416138] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5591.009234] Lustre: DEBUG MARKER: Iteration 6 [ 5591.150544] LustreError: 301604:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5591.152558] LustreError: 301605:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5591.155717] LustreError: 301604:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5592.233373] Lustre: Mounted lustre-client [ 5592.710952] LustreError: 301709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe9250e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5592.715736] LustreError: 301709:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5592.740242] Lustre: Unmounted lustre-client [ 5593.812652] Key type lgssc unregistered [ 5593.942473] LNet: 301953:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5593.945654] LNetError: 301953:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5593.953622] LNet: Removed LNI 192.168.203.1@tcp [ 5594.225108] Key type .llcrypt unregistered [ 5594.226350] Key type ._llcrypt unregistered [ 5594.637777] Key type ._llcrypt registered [ 5594.639374] Key type .llcrypt registered [ 5594.735823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5594.741291] alg: No test for adler32 (adler32-zlib) [ 5595.629365] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5595.728471] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5597.343155] Key type lgssc registered [ 5597.929364] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5603.622879] Lustre: DEBUG MARKER: Iteration 7 [ 5603.834815] LustreError: 302776:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5603.836476] LustreError: 302775:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5603.845102] LustreError: 302776:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5604.960286] Lustre: Mounted lustre-client [ 5605.514881] LustreError: 302880:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeafc4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5605.534093] Lustre: Unmounted lustre-client [ 5606.697712] Key type lgssc unregistered [ 5606.826598] LNet: 303129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5606.830243] LNetError: 303129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5606.839932] LNet: Removed LNI 192.168.203.1@tcp [ 5607.151287] Key type .llcrypt unregistered [ 5607.153560] Key type ._llcrypt unregistered [ 5607.607868] Key type ._llcrypt registered [ 5607.608957] Key type .llcrypt registered [ 5607.718273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5607.723672] alg: No test for adler32 (adler32-zlib) [ 5608.600764] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5608.700618] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5610.311140] Key type lgssc registered [ 5610.833446] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5615.481938] Lustre: DEBUG MARKER: Iteration 8 [ 5615.644576] LustreError: 303950:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5615.644938] LustreError: 303951:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5615.653790] LustreError: 303950:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5617.783829] Lustre: Mounted lustre-client [ 5617.786226] Lustre: Skipped 1 previous similar message [ 5618.320191] LustreError: 304058:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe83352000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5618.342371] Lustre: Unmounted lustre-client [ 5619.541446] Key type lgssc unregistered [ 5619.684436] LNet: 304301:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5619.688822] LNetError: 304301:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5619.699555] LNet: Removed LNI 192.168.203.1@tcp [ 5619.974202] Key type .llcrypt unregistered [ 5619.975866] Key type ._llcrypt unregistered [ 5620.298975] Key type ._llcrypt registered [ 5620.300514] Key type .llcrypt registered [ 5620.513587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5620.520122] alg: No test for adler32 (adler32-zlib) [ 5621.403207] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5621.506144] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5623.095153] Key type lgssc registered [ 5623.491516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5627.847895] Lustre: DEBUG MARKER: Iteration 9 [ 5627.964474] LustreError: 305124:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5627.965700] LustreError: 305122:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5627.978129] LustreError: 305124:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 5629.078277] Lustre: Mounted lustre-client [ 5629.781858] LustreError: 305226:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe85d4b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5629.808087] Lustre: Unmounted lustre-client [ 5631.278441] Key type lgssc unregistered [ 5631.437767] LNet: 305478:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5631.449591] LNetError: 305478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5632.487098] LNet: Removed LNI 192.168.203.1@tcp [ 5632.834231] Key type .llcrypt unregistered [ 5632.837201] Key type ._llcrypt unregistered [ 5633.311606] Key type ._llcrypt registered [ 5633.315875] Key type .llcrypt registered [ 5633.615234] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5633.626177] alg: No test for adler32 (adler32-zlib) [ 5634.568787] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5634.750156] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5636.367168] Key type lgssc registered [ 5636.992055] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5642.077914] Lustre: DEBUG MARKER: Iteration 10 [ 5642.260328] LustreError: 306298:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5642.260947] LustreError: 306299:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5642.267941] LustreError: 306298:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5643.356255] Lustre: Mounted lustre-client [ 5643.359097] Lustre: Skipped 1 previous similar message [ 5643.952983] LustreError: 306403:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e8d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5643.987604] Lustre: Unmounted lustre-client [ 5645.366362] Key type lgssc unregistered [ 5645.531974] LNet: 306653:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5645.537156] LNetError: 306653:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5645.549868] LNet: Removed LNI 192.168.203.1@tcp [ 5645.894119] Key type .llcrypt unregistered [ 5645.895087] Key type ._llcrypt unregistered [ 5646.291540] Key type ._llcrypt registered [ 5646.294513] Key type .llcrypt registered [ 5646.502931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5646.508858] alg: No test for adler32 (adler32-zlib) [ 5647.413745] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5647.550480] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5649.159368] Key type lgssc registered [ 5649.772261] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5654.289586] Lustre: DEBUG MARKER: Iteration 11 [ 5654.434418] LustreError: 307473:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5654.436232] LustreError: 307474:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5654.442939] LustreError: 307473:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5655.535249] Lustre: Mounted lustre-client [ 5656.167996] LustreError: 307578:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe99a7f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5656.197110] Lustre: Unmounted lustre-client [ 5657.482644] Key type lgssc unregistered [ 5657.601603] LNet: 307827:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5657.603980] LNetError: 307827:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5657.612409] LNet: Removed LNI 192.168.203.1@tcp [ 5657.901102] Key type .llcrypt unregistered [ 5657.903653] Key type ._llcrypt unregistered [ 5658.232875] Key type ._llcrypt registered [ 5658.236702] Key type .llcrypt registered [ 5658.468912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5658.475055] alg: No test for adler32 (adler32-zlib) [ 5659.397110] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5659.548916] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5661.175172] Key type lgssc registered [ 5662.013301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5667.108382] Lustre: DEBUG MARKER: Iteration 12 [ 5667.281438] LustreError: 308650:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5667.284082] LustreError: 308651:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5667.293558] LustreError: 308650:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5668.390343] Lustre: Mounted lustre-client [ 5668.393974] Lustre: Skipped 1 previous similar message [ 5669.027523] LustreError: 308752:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeb565b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5669.051071] Lustre: Unmounted lustre-client [ 5670.367667] Key type lgssc unregistered [ 5670.522307] LNet: 309002:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5670.524910] LNetError: 309002:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5670.532705] LNet: Removed LNI 192.168.203.1@tcp [ 5670.826124] Key type .llcrypt unregistered [ 5670.828111] Key type ._llcrypt unregistered [ 5671.162509] Key type ._llcrypt registered [ 5671.164573] Key type .llcrypt registered [ 5671.387880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5671.401093] alg: No test for adler32 (adler32-zlib) [ 5672.321020] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5672.406081] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5674.007146] Key type lgssc registered [ 5674.606566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5679.919654] Lustre: DEBUG MARKER: Iteration 13 [ 5680.113199] LustreError: 309824:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5680.113348] LustreError: 309823:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5680.120618] LustreError: 309824:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5681.210596] Lustre: Mounted lustre-client [ 5681.731271] LustreError: 309921:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe9941c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5681.763305] Lustre: Unmounted lustre-client [ 5682.834555] Key type lgssc unregistered [ 5682.970534] LNet: 310172:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5682.973104] LNetError: 310172:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5682.987973] LNet: Removed LNI 192.168.203.1@tcp [ 5683.315127] Key type .llcrypt unregistered [ 5683.318041] Key type ._llcrypt unregistered [ 5683.688182] Key type ._llcrypt registered [ 5683.689702] Key type .llcrypt registered [ 5683.870861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5683.876596] alg: No test for adler32 (adler32-zlib) [ 5684.750783] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5684.851814] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5686.447152] Key type lgssc registered [ 5686.857729] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5691.055305] Lustre: DEBUG MARKER: Iteration 14 [ 5691.247318] LustreError: 310993:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5691.247504] LustreError: 310992:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5691.255955] LustreError: 310993:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5692.344804] Lustre: Mounted lustre-client [ 5693.007904] LustreError: 311101:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e8f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5693.013042] LustreError: 311101:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5693.040220] Lustre: Unmounted lustre-client [ 5694.219367] Key type lgssc unregistered [ 5694.364309] LNet: 311343:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5694.370397] LNetError: 311343:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5694.380973] LNet: Removed LNI 192.168.203.1@tcp [ 5694.713174] Key type .llcrypt unregistered [ 5694.714489] Key type ._llcrypt unregistered [ 5695.224392] Key type ._llcrypt registered [ 5695.225822] Key type .llcrypt registered [ 5695.327390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5695.334515] alg: No test for adler32 (adler32-zlib) [ 5696.230735] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5696.347852] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5697.959165] Key type lgssc registered [ 5698.389745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5702.044650] Lustre: DEBUG MARKER: Iteration 15 [ 5702.162295] LustreError: 312164:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5702.162345] LustreError: 312165:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5702.167449] LustreError: 312164:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5703.224303] Lustre: Mounted lustre-client [ 5703.226923] Lustre: Skipped 1 previous similar message [ 5703.668590] LustreError: 312273:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe884b6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5703.711512] Lustre: Unmounted lustre-client [ 5704.928129] Key type lgssc unregistered [ 5705.083355] LNet: 312516:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5705.086197] LNetError: 312516:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5705.097883] LNet: Removed LNI 192.168.203.1@tcp [ 5705.396178] Key type .llcrypt unregistered [ 5705.397964] Key type ._llcrypt unregistered [ 5705.764868] Key type ._llcrypt registered [ 5705.766388] Key type .llcrypt registered [ 5706.011655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5706.017052] alg: No test for adler32 (adler32-zlib) [ 5706.932217] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5707.031732] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5708.631164] Key type lgssc registered [ 5709.092180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5713.472300] Lustre: DEBUG MARKER: Iteration 16 [ 5713.608942] LustreError: 313338:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5713.612536] LustreError: 313339:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5713.616545] LustreError: 313338:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5714.721986] Lustre: Mounted lustre-client [ 5715.213415] LustreError: 313451:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e8c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5715.218201] LustreError: 313451:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5715.239913] Lustre: Unmounted lustre-client [ 5716.415853] Key type lgssc unregistered [ 5716.540399] LNet: 313693:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5716.543131] LNetError: 313693:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5716.551027] LNet: Removed LNI 192.168.203.1@tcp [ 5716.812108] Key type .llcrypt unregistered [ 5716.813974] Key type ._llcrypt unregistered [ 5717.139725] Key type ._llcrypt registered [ 5717.141695] Key type .llcrypt registered [ 5717.325741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5717.334103] alg: No test for adler32 (adler32-zlib) [ 5718.281529] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5718.387988] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5719.999696] Key type lgssc registered [ 5720.673051] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5726.186746] Lustre: DEBUG MARKER: Iteration 17 [ 5726.366347] LustreError: 314515:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5726.366392] LustreError: 314516:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5726.375391] LustreError: 314515:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5727.494225] Lustre: Mounted lustre-client [ 5728.092568] LustreError: 314623:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84d33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5728.097184] LustreError: 314623:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5728.134124] Lustre: Unmounted lustre-client [ 5729.333843] Key type lgssc unregistered [ 5729.448405] LNet: 314866:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5729.451359] LNetError: 314866:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5729.460739] LNet: Removed LNI 192.168.203.1@tcp [ 5729.710109] Key type .llcrypt unregistered [ 5729.711355] Key type ._llcrypt unregistered [ 5729.988553] Key type ._llcrypt registered [ 5730.000535] Key type .llcrypt registered [ 5730.191305] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5730.197280] alg: No test for adler32 (adler32-zlib) [ 5731.069079] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5731.153609] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5732.743157] Key type lgssc registered [ 5733.147146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5737.556729] Lustre: DEBUG MARKER: Iteration 18 [ 5737.724420] LustreError: 315687:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5737.725067] LustreError: 315688:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5737.731755] LustreError: 315687:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5738.821724] Lustre: Mounted lustre-client [ 5739.518140] LustreError: 315797:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89272800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5739.547086] Lustre: Unmounted lustre-client [ 5740.692489] Key type lgssc unregistered [ 5740.848617] LNet: 316039:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5740.851538] LNetError: 316039:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5740.861857] LNet: Removed LNI 192.168.203.1@tcp [ 5741.159230] Key type .llcrypt unregistered [ 5741.161203] Key type ._llcrypt unregistered [ 5741.459134] Key type ._llcrypt registered [ 5741.460645] Key type .llcrypt registered [ 5741.663770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5741.670035] alg: No test for adler32 (adler32-zlib) [ 5742.545243] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5742.640419] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5744.223383] Key type lgssc registered [ 5744.689297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5748.617734] Lustre: DEBUG MARKER: Iteration 19 [ 5748.765060] LustreError: 316860:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5748.765066] LustreError: 316861:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5748.772748] LustreError: 316860:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 5749.866950] Lustre: Mounted lustre-client [ 5750.455682] LustreError: 316961:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e10800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5750.478330] Lustre: Unmounted lustre-client [ 5751.862707] Key type lgssc unregistered [ 5752.029729] LNet: 317211:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5752.034457] LNetError: 317211:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5752.043158] LNet: Removed LNI 192.168.203.1@tcp [ 5752.364368] Key type .llcrypt unregistered [ 5752.366400] Key type ._llcrypt unregistered [ 5752.761091] Key type ._llcrypt registered [ 5752.768175] Key type .llcrypt registered [ 5752.873479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5752.880434] alg: No test for adler32 (adler32-zlib) [ 5753.867440] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5754.034753] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5755.671164] Key type lgssc registered [ 5756.274060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5761.363483] Lustre: DEBUG MARKER: Iteration 20 [ 5761.521911] LustreError: 318033:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5761.522272] LustreError: 318034:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5761.531498] LustreError: 318033:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5762.642881] Lustre: Mounted lustre-client [ 5763.221516] LustreError: 318141:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeb0918000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5763.263183] Lustre: Unmounted lustre-client [ 5764.767943] Key type lgssc unregistered [ 5764.933798] LNet: 318385:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5764.939554] LNetError: 318385:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5764.955994] LNet: Removed LNI 192.168.203.1@tcp [ 5765.333416] Key type .llcrypt unregistered [ 5765.335254] Key type ._llcrypt unregistered [ 5765.778855] Key type ._llcrypt registered [ 5765.782232] Key type .llcrypt registered [ 5766.016674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5766.025538] alg: No test for adler32 (adler32-zlib) [ 5766.951466] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5767.080797] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5768.687137] Key type lgssc registered [ 5769.325301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5774.434096] Lustre: DEBUG MARKER: Iteration 21 [ 5774.610694] LustreError: 319205:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5774.612562] LustreError: 319212:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5774.617033] LustreError: 319205:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5775.726902] Lustre: Mounted lustre-client [ 5775.728945] Lustre: Skipped 1 previous similar message [ 5776.329761] LustreError: 319313:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe91d68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5776.379105] Lustre: Unmounted lustre-client [ 5776.383765] Lustre: Skipped 1 previous similar message [ 5777.724704] Key type lgssc unregistered [ 5777.863626] LNet: 319556:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5777.866740] LNetError: 319556:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5777.876662] LNet: Removed LNI 192.168.203.1@tcp [ 5778.165361] Key type .llcrypt unregistered [ 5778.166712] Key type ._llcrypt unregistered [ 5778.523893] Key type ._llcrypt registered [ 5778.525305] Key type .llcrypt registered [ 5778.750839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5778.761480] alg: No test for adler32 (adler32-zlib) [ 5779.709918] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5779.858490] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5781.463139] Key type lgssc registered [ 5781.967786] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5786.642806] Lustre: DEBUG MARKER: Iteration 22 [ 5786.780442] LustreError: 320379:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5786.780480] LustreError: 320378:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5786.786985] LustreError: 320379:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5787.868845] Lustre: Mounted lustre-client [ 5788.368874] LustreError: 320484:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89275000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5788.411079] Lustre: Unmounted lustre-client [ 5789.708268] Key type lgssc unregistered [ 5789.854717] LNet: 320731:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5789.861615] LNetError: 320731:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5789.874475] LNet: Removed LNI 192.168.203.1@tcp [ 5790.220151] Key type .llcrypt unregistered [ 5790.222578] Key type ._llcrypt unregistered [ 5790.617898] Key type ._llcrypt registered [ 5790.618883] Key type .llcrypt registered [ 5790.864249] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5790.871840] alg: No test for adler32 (adler32-zlib) [ 5791.786062] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5791.892880] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5793.495197] Key type lgssc registered [ 5794.140118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5799.399377] Lustre: DEBUG MARKER: Iteration 23 [ 5799.569505] LustreError: 321551:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5799.569735] LustreError: 321552:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5799.581386] LustreError: 321551:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5801.757527] Lustre: Mounted lustre-client [ 5802.362466] LustreError: 321655:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe98bd1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5802.398085] Lustre: Unmounted lustre-client [ 5803.719256] Key type lgssc unregistered [ 5803.870650] LNet: 321906:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5803.874402] LNetError: 321906:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5803.887510] LNet: Removed LNI 192.168.203.1@tcp [ 5804.263970] Key type .llcrypt unregistered [ 5804.265420] Key type ._llcrypt unregistered [ 5804.698233] Key type ._llcrypt registered [ 5804.700920] Key type .llcrypt registered [ 5804.959493] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5804.971313] alg: No test for adler32 (adler32-zlib) [ 5805.875138] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5806.007214] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5807.615134] Key type lgssc registered [ 5808.175318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5814.001104] Lustre: DEBUG MARKER: Iteration 24 [ 5814.185896] LustreError: 322724:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5814.190088] LustreError: 322731:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5814.200628] LustreError: 322724:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5815.331375] Lustre: Mounted lustre-client [ 5815.336222] Lustre: Skipped 1 previous similar message [ 5816.368603] LustreError: 322831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89e1c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5816.404391] Lustre: Unmounted lustre-client [ 5817.857379] Key type lgssc unregistered [ 5818.005653] LNet: 323078:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5818.011162] LNetError: 323078:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5818.027824] LNet: Removed LNI 192.168.203.1@tcp [ 5818.420340] Key type .llcrypt unregistered [ 5818.421791] Key type ._llcrypt unregistered [ 5818.883532] Key type ._llcrypt registered [ 5818.886467] Key type .llcrypt registered [ 5819.182701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5819.194211] alg: No test for adler32 (adler32-zlib) [ 5820.159983] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5820.291510] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5821.927160] Key type lgssc registered [ 5822.599384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5828.222749] Lustre: DEBUG MARKER: Iteration 25 [ 5828.417424] LustreError: 323899:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5828.418733] LustreError: 323900:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5828.424145] LustreError: 323899:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5829.511609] Lustre: Mounted lustre-client [ 5830.047305] LustreError: 324004:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe83ea9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5830.079324] Lustre: Unmounted lustre-client [ 5831.513699] Key type lgssc unregistered [ 5831.658570] LNet: 324253:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5831.664384] LNetError: 324253:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5831.685184] LNet: Removed LNI 192.168.203.1@tcp [ 5832.038114] Key type .llcrypt unregistered [ 5832.039483] Key type ._llcrypt unregistered [ 5832.446653] Key type ._llcrypt registered [ 5832.448405] Key type .llcrypt registered [ 5832.643611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5832.652432] alg: No test for adler32 (adler32-zlib) [ 5833.566548] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5833.687220] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5835.295158] Key type lgssc registered [ 5836.055925] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5841.750850] Lustre: DEBUG MARKER: Iteration 26 [ 5841.983491] LustreError: 325075:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5841.985577] LustreError: 325076:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5841.993263] LustreError: 325075:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5843.137714] Lustre: Mounted lustre-client [ 5844.036760] LustreError: 325180:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeb565e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5844.078153] Lustre: Unmounted lustre-client [ 5845.482463] Key type lgssc unregistered [ 5845.667561] LNet: 325429:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5845.675247] LNetError: 325429:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5845.686784] LNet: Removed LNI 192.168.203.1@tcp [ 5846.035165] Key type .llcrypt unregistered [ 5846.036738] Key type ._llcrypt unregistered [ 5846.623157] Key type ._llcrypt registered [ 5846.648205] Key type .llcrypt registered [ 5846.781605] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5846.788509] alg: No test for adler32 (adler32-zlib) [ 5847.724363] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5847.884214] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5849.487216] Key type lgssc registered [ 5850.275612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5856.345776] Lustre: DEBUG MARKER: Iteration 27 [ 5856.563510] LustreError: 326252:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5856.564023] LustreError: 326251:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5856.573366] LustreError: 326252:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5857.735874] Lustre: Mounted lustre-client [ 5858.415597] LustreError: 326362:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89277000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5858.450108] Lustre: Unmounted lustre-client [ 5859.985096] Key type lgssc unregistered [ 5860.146809] LNet: 326605:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5860.155983] LNetError: 326605:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5860.164547] LNet: Removed LNI 192.168.203.1@tcp [ 5860.549237] Key type .llcrypt unregistered [ 5860.550527] Key type ._llcrypt unregistered [ 5860.983407] Key type ._llcrypt registered [ 5860.985201] Key type .llcrypt registered [ 5861.221872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5861.233778] alg: No test for adler32 (adler32-zlib) [ 5862.159348] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5862.267683] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5863.882676] Key type lgssc registered [ 5864.525086] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5870.781607] Lustre: DEBUG MARKER: Iteration 28 [ 5870.985759] LustreError: 327428:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5870.986176] LustreError: 327427:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5870.997192] LustreError: 327428:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5872.134335] Lustre: Mounted lustre-client [ 5872.137153] Lustre: Skipped 1 previous similar message [ 5873.181990] LustreError: 327537:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89e94000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5873.188501] LustreError: 327537:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5873.216049] Lustre: Unmounted lustre-client [ 5874.639531] Key type lgssc unregistered [ 5874.774492] LNet: 327779:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5874.777265] LNetError: 327779:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5874.786591] LNet: Removed LNI 192.168.203.1@tcp [ 5875.168157] Key type .llcrypt unregistered [ 5875.170966] Key type ._llcrypt unregistered [ 5875.707582] Key type ._llcrypt registered [ 5875.709113] Key type .llcrypt registered [ 5875.818797] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5875.826300] alg: No test for adler32 (adler32-zlib) [ 5876.755920] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5876.913621] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5878.551156] Key type lgssc registered [ 5879.226928] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5885.801676] Lustre: DEBUG MARKER: Iteration 29 [ 5885.991625] LustreError: 328599:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5885.993936] LustreError: 328601:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5886.001283] LustreError: 328599:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5887.129469] Lustre: Mounted lustre-client [ 5888.191652] LustreError: 328708:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe838a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5888.195332] LustreError: 328708:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5888.221855] Lustre: Unmounted lustre-client [ 5889.612314] Key type lgssc unregistered [ 5889.760574] LNet: 328950:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5889.766689] LNetError: 328950:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5889.782625] LNet: Removed LNI 192.168.203.1@tcp [ 5890.138390] Key type .llcrypt unregistered [ 5890.141150] Key type ._llcrypt unregistered [ 5890.530219] Key type ._llcrypt registered [ 5890.538308] Key type .llcrypt registered [ 5890.772856] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5890.786838] alg: No test for adler32 (adler32-zlib) [ 5891.734974] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5891.877623] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5893.519688] Key type lgssc registered [ 5894.234647] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5899.795273] Lustre: DEBUG MARKER: Iteration 30 [ 5899.984313] LustreError: 329771:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5899.984431] LustreError: 329772:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5899.997694] LustreError: 329771:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 5901.126809] Lustre: Mounted lustre-client [ 5901.833934] LustreError: 329883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84d31800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5901.839098] LustreError: 329883:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 5901.872118] Lustre: Unmounted lustre-client [ 5903.378217] Key type lgssc unregistered [ 5903.561503] LNet: 330125:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5903.570893] LNetError: 330125:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5903.584461] LNet: Removed LNI 192.168.203.1@tcp [ 5903.999271] Key type .llcrypt unregistered [ 5904.001554] Key type ._llcrypt unregistered [ 5904.394526] Key type ._llcrypt registered [ 5904.395963] Key type .llcrypt registered [ 5904.645881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5904.664654] alg: No test for adler32 (adler32-zlib) [ 5905.637480] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5905.785035] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5907.415257] Key type lgssc registered [ 5908.158113] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5913.965438] Lustre: DEBUG MARKER: Iteration 31 [ 5914.238860] LustreError: 330946:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5914.238891] LustreError: 330947:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5914.250693] LustreError: 330946:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5915.413590] Lustre: Mounted lustre-client [ 5915.415115] Lustre: Skipped 1 previous similar message [ 5916.374265] LustreError: 331057:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89d6e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5916.403705] Lustre: Unmounted lustre-client [ 5917.791398] Key type lgssc unregistered [ 5917.953526] LNet: 331300:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5917.958069] LNetError: 331300:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5917.971197] LNet: Removed LNI 192.168.203.1@tcp [ 5918.284164] Key type .llcrypt unregistered [ 5918.285710] Key type ._llcrypt unregistered [ 5918.704654] Key type ._llcrypt registered [ 5918.708748] Key type .llcrypt registered [ 5918.949554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5918.958410] alg: No test for adler32 (adler32-zlib) [ 5919.878115] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5920.003970] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5921.623185] Key type lgssc registered [ 5922.314172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5928.136275] Lustre: DEBUG MARKER: Iteration 32 [ 5928.366366] LustreError: 332121:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5928.366938] LustreError: 332122:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5928.374456] LustreError: 332121:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5930.538199] Lustre: Mounted lustre-client [ 5931.095211] LustreError: 332229:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeafc4a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5931.128248] Lustre: Unmounted lustre-client [ 5932.572729] Key type lgssc unregistered [ 5932.726611] LNet: 332473:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5932.732635] LNetError: 332473:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5932.745517] LNet: Removed LNI 192.168.203.1@tcp [ 5933.072179] Key type .llcrypt unregistered [ 5933.073864] Key type ._llcrypt unregistered [ 5933.463485] Key type ._llcrypt registered [ 5933.465284] Key type .llcrypt registered [ 5933.727576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5933.735560] alg: No test for adler32 (adler32-zlib) [ 5934.689901] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5934.821988] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5936.423182] Key type lgssc registered [ 5937.026174] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5942.564698] Lustre: DEBUG MARKER: Iteration 33 [ 5942.713860] LustreError: 333293:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5942.713897] LustreError: 333294:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5942.719680] LustreError: 333293:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5943.813806] Lustre: Mounted lustre-client [ 5944.450190] LustreError: 333398:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84d34000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5944.492063] Lustre: Unmounted lustre-client [ 5945.941452] Key type lgssc unregistered [ 5946.104681] LNet: 333641:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5946.108906] LNetError: 333641:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5946.120258] LNet: Removed LNI 192.168.203.1@tcp [ 5946.435140] Key type .llcrypt unregistered [ 5946.436516] Key type ._llcrypt unregistered [ 5946.872839] Key type ._llcrypt registered [ 5946.878546] Key type .llcrypt registered [ 5947.120381] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5947.125804] alg: No test for adler32 (adler32-zlib) [ 5948.082245] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5948.255316] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5949.871645] Key type lgssc registered [ 5950.463885] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5955.745248] Lustre: DEBUG MARKER: Iteration 34 [ 5955.891488] LustreError: 334462:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5955.893239] LustreError: 334463:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5955.899518] LustreError: 334462:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5957.012191] Lustre: Mounted lustre-client [ 5957.595701] LustreError: 334567:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89271800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5957.643582] Lustre: Unmounted lustre-client [ 5957.646700] Lustre: Skipped 1 previous similar message [ 5959.092708] Key type lgssc unregistered [ 5959.244278] LNet: 334810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5959.248347] LNetError: 334810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5959.268260] LNet: Removed LNI 192.168.203.1@tcp [ 5959.626327] Key type .llcrypt unregistered [ 5959.627925] Key type ._llcrypt unregistered [ 5960.164094] Key type ._llcrypt registered [ 5960.165678] Key type .llcrypt registered [ 5960.356939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5960.367835] alg: No test for adler32 (adler32-zlib) [ 5961.325570] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5961.447993] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5963.079280] Key type lgssc registered [ 5963.712929] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5968.967530] Lustre: DEBUG MARKER: Iteration 35 [ 5969.179713] LustreError: 335630:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5969.179752] LustreError: 335633:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5969.186112] LustreError: 335630:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5970.307494] Lustre: Mounted lustre-client [ 5971.016179] LustreError: 335735:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe83353000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5971.049112] Lustre: Unmounted lustre-client [ 5972.534235] Key type lgssc unregistered [ 5972.677958] LNet: 335985:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5972.682261] LNetError: 335985:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5972.691611] LNet: Removed LNI 192.168.203.1@tcp [ 5973.046119] Key type .llcrypt unregistered [ 5973.049084] Key type ._llcrypt unregistered [ 5973.598487] Key type ._llcrypt registered [ 5973.603074] Key type .llcrypt registered [ 5973.817453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5973.825706] alg: No test for adler32 (adler32-zlib) [ 5974.855787] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5975.006730] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5976.639164] Key type lgssc registered [ 5977.266233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5983.332581] Lustre: DEBUG MARKER: Iteration 36 [ 5983.538698] LustreError: 336806:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5983.539193] LustreError: 336807:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5983.547967] LustreError: 336806:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5984.678132] Lustre: Mounted lustre-client [ 5985.633108] LustreError: 336906:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89270000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5985.664069] Lustre: Unmounted lustre-client [ 5987.196379] Key type lgssc unregistered [ 5987.347892] LNet: 337153:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5987.354316] LNetError: 337153:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5987.363325] LNet: Removed LNI 192.168.203.1@tcp [ 5987.771276] Key type .llcrypt unregistered [ 5987.773880] Key type ._llcrypt unregistered [ 5988.311538] Key type ._llcrypt registered [ 5988.315820] Key type .llcrypt registered [ 5988.582869] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5988.589261] alg: No test for adler32 (adler32-zlib) [ 5989.514371] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 5989.670455] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 5991.303157] Key type lgssc registered [ 5992.114397] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5998.519913] Lustre: DEBUG MARKER: Iteration 37 [ 5998.759396] LustreError: 337977:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5998.759421] LustreError: 337976:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5998.766071] LustreError: 337977:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5999.874551] Lustre: Mounted lustre-client [ 6000.808626] LustreError: 338077:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe86595800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6000.847207] Lustre: Unmounted lustre-client [ 6002.371548] Key type lgssc unregistered [ 6002.498995] LNet: 338326:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6002.505210] LNetError: 338326:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6002.516262] LNet: Removed LNI 192.168.203.1@tcp [ 6002.916154] Key type .llcrypt unregistered [ 6002.917537] Key type ._llcrypt unregistered [ 6003.523807] Key type ._llcrypt registered [ 6003.525470] Key type .llcrypt registered [ 6003.659501] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6003.668492] alg: No test for adler32 (adler32-zlib) [ 6004.619754] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6004.768770] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6006.407450] Key type lgssc registered [ 6007.085297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6012.956634] Lustre: DEBUG MARKER: Iteration 38 [ 6013.122030] LustreError: 339147:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6013.122375] LustreError: 339148:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6013.130420] LustreError: 339147:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6014.268922] Lustre: Mounted lustre-client [ 6015.262802] LustreError: 339248:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe86595800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6015.307333] Lustre: Unmounted lustre-client [ 6017.116727] Key type lgssc unregistered [ 6017.294393] LNet: 339498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6017.300303] LNetError: 339498:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6017.316197] LNet: Removed LNI 192.168.203.1@tcp [ 6017.667145] Key type .llcrypt unregistered [ 6017.671090] Key type ._llcrypt unregistered [ 6018.070701] Key type ._llcrypt registered [ 6018.072489] Key type .llcrypt registered [ 6018.256164] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6018.264644] alg: No test for adler32 (adler32-zlib) [ 6019.185365] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6019.330201] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6020.967209] Key type lgssc registered [ 6021.681596] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6027.723108] Lustre: DEBUG MARKER: Iteration 39 [ 6027.933882] LustreError: 340316:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6027.934132] LustreError: 340325:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6027.942091] LustreError: 340316:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6029.050517] Lustre: Mounted lustre-client [ 6030.021815] LustreError: 340425:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe86743000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6030.053125] Lustre: Unmounted lustre-client [ 6031.505751] Key type lgssc unregistered [ 6031.669679] LNet: 340668:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6031.673759] LNetError: 340668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6031.686412] LNet: Removed LNI 192.168.203.1@tcp [ 6032.105232] Key type .llcrypt unregistered [ 6032.106427] Key type ._llcrypt unregistered [ 6032.578260] Key type ._llcrypt registered [ 6032.582623] Key type .llcrypt registered [ 6032.918441] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6032.932426] alg: No test for adler32 (adler32-zlib) [ 6033.879374] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6034.040338] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6035.663125] Key type lgssc registered [ 6036.350758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6042.090968] Lustre: DEBUG MARKER: Iteration 40 [ 6042.268037] LustreError: 341489:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6042.268667] LustreError: 341491:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6042.275959] LustreError: 341489:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6043.395911] Lustre: Mounted lustre-client [ 6044.327445] LustreError: 341594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe913b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6044.368771] Lustre: Unmounted lustre-client [ 6045.882673] Key type lgssc unregistered [ 6046.045651] LNet: 341843:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6046.049726] LNetError: 341843:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6046.059537] LNet: Removed LNI 192.168.203.1@tcp [ 6046.416236] Key type .llcrypt unregistered [ 6046.417770] Key type ._llcrypt unregistered [ 6046.826894] Key type ._llcrypt registered [ 6046.830148] Key type .llcrypt registered [ 6047.122935] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6047.130058] alg: No test for adler32 (adler32-zlib) [ 6048.047744] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6048.173117] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6049.807159] Key type lgssc registered [ 6050.477475] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6056.526772] Lustre: DEBUG MARKER: Iteration 41 [ 6056.714457] LustreError: 342666:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6056.714655] LustreError: 342665:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6056.727020] LustreError: 342666:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 6057.912695] Lustre: Mounted lustre-client [ 6058.826896] LustreError: 342773:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe833dd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6058.862866] Lustre: Unmounted lustre-client [ 6060.373556] Key type lgssc unregistered [ 6060.540551] LNet: 343018:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6060.545369] LNetError: 343018:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6060.567954] LNet: Removed LNI 192.168.203.1@tcp [ 6060.933253] Key type .llcrypt unregistered [ 6060.935702] Key type ._llcrypt unregistered [ 6061.335912] Key type ._llcrypt registered [ 6061.339351] Key type .llcrypt registered [ 6061.611269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6061.619336] alg: No test for adler32 (adler32-zlib) [ 6062.660929] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6062.791234] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6064.423197] Key type lgssc registered [ 6065.132444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6071.399226] Lustre: DEBUG MARKER: Iteration 42 [ 6071.618542] LustreError: 343840:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6071.619197] LustreError: 343841:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6071.629318] LustreError: 343840:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 6072.753912] Lustre: Mounted lustre-client [ 6073.692556] LustreError: 343951:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e88000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6073.728038] Lustre: Unmounted lustre-client [ 6075.273856] Key type lgssc unregistered [ 6075.414936] LNet: 344193:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6075.417913] LNetError: 344193:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6075.427078] LNet: Removed LNI 192.168.203.1@tcp [ 6075.771297] Key type .llcrypt unregistered [ 6075.773089] Key type ._llcrypt unregistered [ 6076.247790] Key type ._llcrypt registered [ 6076.251052] Key type .llcrypt registered [ 6076.462941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6076.478633] alg: No test for adler32 (adler32-zlib) [ 6077.400504] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6077.538626] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6079.167380] Key type lgssc registered [ 6079.887443] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6086.969850] Lustre: DEBUG MARKER: Iteration 43 [ 6087.194631] LustreError: 345014:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6087.201370] LustreError: 345016:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6087.207383] LustreError: 345014:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6088.377486] Lustre: Mounted lustre-client [ 6089.089750] LustreError: 345117:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe833d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6089.123214] Lustre: Unmounted lustre-client [ 6090.733554] Key type lgssc unregistered [ 6090.915861] LNet: 345368:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6090.919440] LNetError: 345368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6090.928413] LNet: Removed LNI 192.168.203.1@tcp [ 6091.262223] Key type .llcrypt unregistered [ 6091.264272] Key type ._llcrypt unregistered [ 6091.722493] Key type ._llcrypt registered [ 6091.726022] Key type .llcrypt registered [ 6091.996854] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6092.010888] alg: No test for adler32 (adler32-zlib) [ 6092.981574] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6093.109890] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6094.735138] Key type lgssc registered [ 6095.519750] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6101.752786] Lustre: DEBUG MARKER: Iteration 44 [ 6101.974668] LustreError: 346191:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6101.974779] LustreError: 346192:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6101.981951] LustreError: 346191:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6103.131513] Lustre: Mounted lustre-client [ 6104.035974] LustreError: 346300:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89e91000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6104.041987] LustreError: 346300:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6104.080065] Lustre: Unmounted lustre-client [ 6104.082596] Lustre: Skipped 1 previous similar message [ 6105.675659] Key type lgssc unregistered [ 6105.853389] LNet: 346542:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6105.859759] LNetError: 346542:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6105.874772] LNet: Removed LNI 192.168.203.1@tcp [ 6106.257068] Key type .llcrypt unregistered [ 6106.258128] Key type ._llcrypt unregistered [ 6106.910476] Key type ._llcrypt registered [ 6106.911942] Key type .llcrypt registered [ 6107.113341] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6107.123530] alg: No test for adler32 (adler32-zlib) [ 6108.061094] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6108.216678] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6109.863633] Key type lgssc registered [ 6110.603512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6116.848675] Lustre: DEBUG MARKER: Iteration 45 [ 6117.040844] LustreError: 347366:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6117.040923] LustreError: 347365:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6117.047639] LustreError: 347366:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6118.190316] Lustre: Mounted lustre-client [ 6119.274505] LustreError: 347467:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89273000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6119.310123] Lustre: Unmounted lustre-client [ 6120.872495] Key type lgssc unregistered [ 6121.034246] LNet: 347716:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6121.044270] LNetError: 347716:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6121.059659] LNet: Removed LNI 192.168.203.1@tcp [ 6121.492146] Key type .llcrypt unregistered [ 6121.493506] Key type ._llcrypt unregistered [ 6122.066628] Key type ._llcrypt registered [ 6122.069052] Key type .llcrypt registered [ 6122.295404] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6122.303252] alg: No test for adler32 (adler32-zlib) [ 6123.250954] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6123.395721] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6125.015243] Key type lgssc registered [ 6125.667935] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6131.548217] Lustre: DEBUG MARKER: Iteration 46 [ 6131.767784] LustreError: 348536:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6131.769853] LustreError: 348540:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6131.778607] LustreError: 348536:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6132.889309] Lustre: Mounted lustre-client [ 6133.873610] LustreError: 348640:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe89277000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6133.906174] Lustre: Unmounted lustre-client [ 6135.530443] Key type lgssc unregistered [ 6135.694735] LNet: 348889:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6135.699014] LNetError: 348889:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6136.742995] LNet: Removed LNI 192.168.203.1@tcp [ 6137.155202] Key type .llcrypt unregistered [ 6137.158194] Key type ._llcrypt unregistered [ 6137.751970] Key type ._llcrypt registered [ 6137.754401] Key type .llcrypt registered [ 6138.029425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6138.037264] alg: No test for adler32 (adler32-zlib) [ 6138.959807] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6139.111488] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6140.751179] Key type lgssc registered [ 6141.370473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6147.109181] Lustre: DEBUG MARKER: Iteration 47 [ 6147.320197] LustreError: 349711:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6147.322756] LustreError: 349712:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6147.326316] LustreError: 349711:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6148.454262] Lustre: Mounted lustre-client [ 6149.403252] LustreError: 349813:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe8318c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6149.432534] Lustre: Unmounted lustre-client [ 6150.912332] Key type lgssc unregistered [ 6151.049125] LNet: 350062:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6151.052326] LNetError: 350062:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6151.060562] LNet: Removed LNI 192.168.203.1@tcp [ 6151.404160] Key type .llcrypt unregistered [ 6151.406264] Key type ._llcrypt unregistered [ 6151.876086] Key type ._llcrypt registered [ 6151.916868] Key type .llcrypt registered [ 6152.122550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6152.130769] alg: No test for adler32 (adler32-zlib) [ 6153.085333] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6153.221276] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6154.823151] Key type lgssc registered [ 6155.435884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6160.806269] Lustre: DEBUG MARKER: Iteration 48 [ 6160.996772] LustreError: 350889:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6160.997686] LustreError: 350883:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6161.004070] LustreError: 350889:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6162.134399] Lustre: Mounted lustre-client [ 6162.136561] Lustre: Skipped 1 previous similar message [ 6163.046815] LustreError: 350994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe87dbd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6163.051609] LustreError: 350994:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6163.074064] Lustre: Unmounted lustre-client [ 6164.664744] Key type lgssc unregistered [ 6164.814925] LNet: 351235:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6164.821403] LNetError: 351235:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6164.833081] LNet: Removed LNI 192.168.203.1@tcp [ 6165.207377] Key type .llcrypt unregistered [ 6165.209783] Key type ._llcrypt unregistered [ 6165.634511] Key type ._llcrypt registered [ 6165.637518] Key type .llcrypt registered [ 6165.902755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6165.908491] alg: No test for adler32 (adler32-zlib) [ 6166.857873] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6166.996993] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6168.607174] Key type lgssc registered [ 6169.310325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6175.575801] Lustre: DEBUG MARKER: Iteration 49 [ 6175.743224] LustreError: 352056:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6175.743550] LustreError: 352057:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6175.750977] LustreError: 352056:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6176.885925] Lustre: Mounted lustre-client [ 6177.916404] LustreError: 352158:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe86742000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6177.956481] Lustre: Unmounted lustre-client [ 6179.447612] Key type lgssc unregistered [ 6179.644646] LNet: 352407:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6179.648158] LNetError: 352407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6179.666618] LNet: Removed LNI 192.168.203.1@tcp [ 6180.067703] Key type .llcrypt unregistered [ 6180.071512] Key type ._llcrypt unregistered [ 6180.629606] Key type ._llcrypt registered [ 6180.631137] Key type .llcrypt registered [ 6180.754642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6180.761674] alg: No test for adler32 (adler32-zlib) [ 6181.666875] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6181.790478] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6183.415275] Key type lgssc registered [ 6184.156116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6190.744300] Lustre: DEBUG MARKER: Iteration 50 [ 6190.928375] LustreError: 353227:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6190.932214] LustreError: 353228:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6190.937339] LustreError: 353227:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6192.080249] Lustre: Mounted lustre-client [ 6193.082686] LustreError: 353334:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfeb1afb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6193.122843] Lustre: Unmounted lustre-client [ 6194.665873] Key type lgssc unregistered [ 6194.824633] LNet: 353583:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6194.829720] LNetError: 353583:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6194.851372] LNet: Removed LNI 192.168.203.1@tcp [ 6195.296303] Key type .llcrypt unregistered [ 6195.303965] Key type ._llcrypt unregistered [ 6195.712103] Key type ._llcrypt registered [ 6195.713573] Key type .llcrypt registered [ 6196.074314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6196.083194] alg: No test for adler32 (adler32-zlib) [ 6197.046914] Lustre: Lustre: Build Version: 2.17.50_152_g82f9dcf [ 6197.212159] LNet: Added LNI 192.168.203.1@tcp [8/256/0/180] [ 6198.871128] Key type lgssc registered [ 6199.607272] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6206.894047] Lustre: Mounted lustre-client [ 6210.858331] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 03:43:15 (1772181795) [ 6226.911179] Lustre: 354919:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181796/real 1772181796] req@ffff8dfe9d749880 x1858267284251776/t0(0) o36->lustre-MDT0000-mdc-ffff8dfe884b7000@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1772181812 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6226.932612] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6226.952742] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6243.297276] Lustre: 354919:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181812/real 1772181812] req@ffff8dfe9d749880 x1858267284251776/t0(0) o36->lustre-MDT0000-mdc-ffff8dfe884b7000@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1772181828 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6243.316221] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6243.340528] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6258.655381] Lustre: 354919:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181828/real 1772181828] req@ffff8dfe9d749880 x1858267284251776/t0(0) o36->lustre-MDT0000-mdc-ffff8dfe884b7000@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1772181844 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6258.684069] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6258.703771] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6275.040354] Lustre: 354919:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181844/real 1772181844] req@ffff8dfe9d749880 x1858267284251776/t0(0) o36->lustre-MDT0000-mdc-ffff8dfe884b7000@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1772181860 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6275.062963] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6275.083148] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6291.423126] Lustre: 354919:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181860/real 1772181860] req@ffff8dfe9d749880 x1858267284251776/t0(0) o36->lustre-MDT0000-mdc-ffff8dfe884b7000@192.168.203.101@tcp:12/10 lens 496/440 e 0 to 1 dl 1772181876 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6291.436684] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection to lustre-MDT0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6291.450514] Lustre: lustre-MDT0000-mdc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6292.256458] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 03:44:36 (1772181876) [ 6293.066487] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 6293.893823] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 03:44:38 (1772181878) [ 6294.754759] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 6295.597160] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 03:44:40 (1772181880) [ 6299.350711] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 03:44:43 (1772181883) [ 6300.251241] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 6301.127130] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 03:44:45 (1772181885) [ 6301.892601] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 6302.880446] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 03:44:47 (1772181887) [ 6303.699124] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 6304.728945] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 03:44:49 (1772181889) [ 6304.922320] LustreError: 357575:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6307.007165] LustreError: 357575:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6310.667219] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 03:44:55 (1772181895) [ 6330.335160] Lustre: 353776:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181899/real 1772181899] req@ffff8dfe9d74c700 x1858267284289280/t0(0) o4->lustre-OST0000-osc-ffff8dfe884b7000@192.168.203.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772181915 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6330.335190] Lustre: lustre-OST0000-osc-ffff8dfe884b7000: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6330.346145] Lustre: 353776:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6330.358764] Lustre: lustre-OST0000-osc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6362.079163] Lustre: 353778:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772181931/real 1772181931] req@ffff8dfe9d749c00 x1858267284289920/t0(0) o4->lustre-OST0000-osc-ffff8dfe884b7000@192.168.203.101@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772181947 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6362.079247] Lustre: lustre-OST0000-osc-ffff8dfe884b7000: Connection to lustre-OST0000 (at 192.168.203.101@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6362.100954] Lustre: 353778:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 6362.115506] Lustre: Skipped 1 previous similar message [ 6362.134298] Lustre: lustre-OST0000-osc-ffff8dfe884b7000: Connection restored to 192.168.203.101@tcp (at 192.168.203.101@tcp) [ 6362.137473] Lustre: Skipped 1 previous similar message [ 6378.679126] Lustre: DEBUG MARKER: oleg301-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8dfe84e89000.ost_server_uuid 50 [ 6379.361745] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8dfe84e89000.ost_server_uuid in IDLE state after 0 sec [ 6380.105385] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6380.934630] Lustre: DEBUG MARKER: == sanityn test complete, duration 6175 sec ============== 03:46:05 (1772181965) [ 6381.656190] Lustre: DEBUG MARKER: === sanityn: start cleanup 03:46:06 (1772181966) === [ 6611.180659] LustreError: 359593:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe84e89000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6611.253192] Lustre: Unmounted lustre-client [ 6615.014521] Lustre: DEBUG MARKER: === sanityn: finish cleanup 03:49:58 (1772182198) === [ 6616.397700] LustreError: 359895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8dfe884b7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6616.403393] LustreError: 359895:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6616.456154] Lustre: Unmounted lustre-client [ 6653.392804] Key type lgssc unregistered [ 6653.616141] LNet: 360381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6653.623136] LNetError: 360381:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6653.643720] LNet: Removed LNI 192.168.203.1@tcp [ 6654.271171] Key type .llcrypt unregistered [ 6654.273368] Key type ._llcrypt unregistered