[ 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.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 576454896 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 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 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 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.002000] x2apic enabled [ 0.003012] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.008035] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.009017] pid_max: default: 32768 minimum: 301 [ 0.010153] LSM: Security Framework initializing [ 0.012076] Yama: becoming mindful. [ 0.013071] SELinux: Initializing. [ 0.014077] *** VALIDATE selinux *** [ 0.020004] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025009] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027184] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028161] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031066] *** VALIDATE tmpfs *** [ 0.033195] *** VALIDATE proc *** [ 0.035036] *** VALIDATE cgroup *** [ 0.036017] *** VALIDATE cgroup2 *** [ 0.038174] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039191] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040018] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041045] Spectre V2 : User space: Vulnerable [ 0.042015] Speculative Store Bypass: Vulnerable [ 0.046766] debug: unmapping init [mem 0xffffffffb4259000-0xffffffffb4260fff] [ 0.049000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049951] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.051030] ... version: 2 [ 0.052014] ... bit width: 48 [ 0.053014] ... generic registers: 4 [ 0.054015] ... value mask: 0000ffffffffffff [ 0.055016] ... max period: 00007fffffffffff [ 0.056011] ... fixed-purpose events: 3 [ 0.057013] ... event mask: 000000070000000f [ 0.058365] rcu: Hierarchical SRCU implementation. [ 0.060713] smp: Bringing up secondary CPUs ... [ 0.061830] x86: Booting SMP configuration: [ 0.062030] .... node #0, CPUs: #1 #2 #3 [ 0.070675] smp: Brought up 1 node, 4 CPUs [ 0.072021] smpboot: Max logical packages: 1 [ 0.073017] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.147513] node 0 deferred pages initialised in 71ms [ 0.159176] devtmpfs: initialized [ 0.164998] x86/mm: Memory block size: 128MB [ 0.169516] gcov: version magic: 0x41383552 [ 0.173358] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.177090] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.179520] pinctrl core: initialized pinctrl subsystem [ 0.181209] [ 0.181737] ************************************************************* [ 0.183014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185014] ** ** [ 0.186011] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.188021] ** ** [ 0.190011] ** This means that this kernel is built to expose internal ** [ 0.192012] ** IOMMU data structures, which may compromise security on ** [ 0.193010] ** your system. ** [ 0.196013] ** ** [ 0.198021] ** If you see this message and you are not debugging the ** [ 0.199010] ** kernel, report this immediately to your vendor! ** [ 0.201293] ** ** [ 0.204016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.205012] ************************************************************* [ 0.208000] NET: Registered protocol family 16 [ 0.209469] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.211052] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.212053] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.215042] cpuidle: using governor menu [ 0.217030] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.219765] PCI: Using configuration type 1 for base access [ 0.222156] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.232070] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.234029] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.239106] cryptd: max_cpu_qlen set to 1000 [ 0.244572] ACPI: Added _OSI(Module Device) [ 0.245000] ACPI: Added _OSI(Processor Device) [ 0.245000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.245000] ACPI: Added _OSI(Processor Aggregator Device) [ 0.248914] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.258909] ACPI: Interpreter enabled [ 0.263260] ACPI: PM: (supports S0 S3 S4 S5) [ 0.265024] ACPI: Using IOAPIC for interrupt routing [ 0.266000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.269807] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.283700] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.285034] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.287019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.289099] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.294623] acpiphp: Slot [2] registered [ 0.296173] acpiphp: Slot [3] registered [ 0.297070] acpiphp: Slot [4] registered [ 0.298130] acpiphp: Slot [5] registered [ 0.301160] acpiphp: Slot [6] registered [ 0.306147] acpiphp: Slot [7] registered [ 0.307000] acpiphp: Slot [8] registered [ 0.307000] acpiphp: Slot [9] registered [ 0.309080] acpiphp: Slot [10] registered [ 0.311083] acpiphp: Slot [11] registered [ 0.312091] acpiphp: Slot [12] registered [ 0.314092] acpiphp: Slot [13] registered [ 0.316127] acpiphp: Slot [14] registered [ 0.318075] acpiphp: Slot [15] registered [ 0.319000] acpiphp: Slot [16] registered [ 0.319000] acpiphp: Slot [17] registered [ 0.319074] acpiphp: Slot [18] registered [ 0.320000] acpiphp: Slot [19] registered [ 0.321093] acpiphp: Slot [20] registered [ 0.322087] acpiphp: Slot [21] registered [ 0.323062] acpiphp: Slot [22] registered [ 0.324074] acpiphp: Slot [23] registered [ 0.325000] acpiphp: Slot [24] registered [ 0.325000] acpiphp: Slot [25] registered [ 0.325074] acpiphp: Slot [26] registered [ 0.326000] acpiphp: Slot [27] registered [ 0.326081] acpiphp: Slot [28] registered [ 0.327000] acpiphp: Slot [29] registered [ 0.328088] acpiphp: Slot [30] registered [ 0.330156] acpiphp: Slot [31] registered [ 0.332070] PCI host bridge to bus 0000:00 [ 0.333017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.337031] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.339027] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.351029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.358036] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.361037] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.366302] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.373000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.375000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.381018] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.385058] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.389040] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.390015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.392015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.395334] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.397712] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.404061] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.406575] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.410026] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.418025] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.422021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.426322] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.430018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.433000] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.439019] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.449043] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.458027] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.461959] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.479021] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.484000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.490404] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.492519] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.495379] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.498195] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.505018] iommu: Default domain type: Passthrough [ 0.508692] SCSI subsystem initialized [ 0.510162] ACPI: bus type USB registered [ 0.512146] usbcore: registered new interface driver usbfs [ 0.518104] usbcore: registered new interface driver hub [ 0.523133] usbcore: registered new device driver usb [ 0.525207] pps_core: LinuxPPS API ver. 1 registered [ 0.527015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.530075] PTP clock support registered [ 0.540235] EDAC MC: Ver: 3.0.0 [ 0.543268] PCI: Using ACPI for IRQ routing [ 0.547449] NetLabel: Initializing [ 0.549014] NetLabel: domain hash size = 128 [ 0.550013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.553246] NetLabel: unlabeled traffic allowed by default [ 0.556029] vgaarb: loaded [ 0.558054] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.560015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.567000] clocksource: Switched to clocksource kvm-clock [ 0.773890] VFS: Disk quotas dquot_6.6.0 [ 0.776051] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.782896] *** VALIDATE ramfs *** [ 0.784348] *** VALIDATE hugetlbfs *** [ 0.786506] pnp: PnP ACPI init [ 0.789752] pnp: PnP ACPI: found 6 devices [ 0.824719] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.830599] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.832911] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.835203] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.837714] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.840070] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.842889] NET: Registered protocol family 2 [ 0.845579] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.851139] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.856380] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.863697] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.872866] TCP: Hash tables configured (established 65536 bind 65536) [ 0.877361] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.880891] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.884110] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.887510] NET: Registered protocol family 1 [ 0.890621] RPC: Registered named UNIX socket transport module. [ 0.892981] RPC: Registered udp transport module. [ 0.894646] RPC: Registered tcp transport module. [ 0.896374] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.899422] NET: Registered protocol family 44 [ 0.903970] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.912363] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.915715] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.921455] PCI: CLS 0 bytes, default 64 [ 0.923464] Unpacking initramfs... [ 2.972111] debug: unmapping init [mem 0xffff8edcfcc64000-0xffff8edcfffcffff] [ 2.978292] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.980459] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.983862] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 3.843172] Initialise system trusted keyrings [ 3.845041] Key type blacklist registered [ 3.847478] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.860105] zbud: loaded [ 3.863205] *** VALIDATE nfs *** [ 3.864544] *** VALIDATE nfs4 *** [ 3.870464] pstore: using deflate compression [ 3.874481] Platform Keyring initialized [ 4.044927] NET: Registered protocol family 38 [ 4.046701] Key type asymmetric registered [ 4.052206] Asymmetric key parser 'x509' registered [ 4.054563] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.061860] io scheduler mq-deadline registered [ 4.068610] io scheduler kyber registered [ 4.070594] io scheduler bfq registered [ 4.074899] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.078729] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.082736] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.086640] ACPI: Power Button [PWRF] [ 4.252344] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.355407] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.476750] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.506225] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.557507] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.565483] Non-volatile memory driver v1.3 [ 4.567463] Linux agpgart interface v0.103 [ 4.599498] virtio_blk virtio1: [vda] 133016 512-byte logical blocks (68.1 MB/64.9 MiB) [ 4.604240] vda: detected capacity change from 0 to 68104192 [ 4.646461] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.649886] vdb: detected capacity change from 0 to 1073741824 [ 4.664259] libphy: Fixed MDIO Bus: probed [ 4.680991] usbcore: registered new interface driver usbserial_generic [ 4.686336] usbserial: USB Serial support registered for generic [ 4.691770] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.696758] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.698459] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.704715] mousedev: PS/2 mouse device common for all mice [ 4.708650] rtc_cmos 00:05: RTC can wake from S4 [ 4.712803] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.719127] rtc_cmos 00:05: registered as rtc0 [ 4.724905] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.728682] intel_pstate: CPU model not supported [ 4.736074] hid: raw HID events driver (C) Jiri Kosina [ 4.736757] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.742422] usbcore: registered new interface driver usbhid [ 4.742431] usbhid: USB HID core driver [ 4.742571] drop_monitor: Initializing network drop monitor service [ 4.742732] Initializing XFRM netlink socket [ 4.743128] NET: Registered protocol family 10 [ 4.747692] Segment Routing with IPv6 [ 4.760938] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.765149] NET: Registered protocol family 17 [ 4.769060] mpls_gso: MPLS GSO support [ 4.792218] RAS: Correctable Errors collector initialized. [ 4.794230] AVX version of gcm_enc/dec engaged. [ 4.795673] AES CTR mode by8 optimization enabled [ 4.938678] sched_clock: Marking stable (4938656180, 0)->(6284546983, -1345890803) [ 4.944766] registered taskstats version 1 [ 4.948407] Loading compiled-in X.509 certificates [ 4.950572] zswap: loaded using pool lzo/zbud [ 4.990305] Key type big_key registered [ 5.038564] Key type encrypted registered [ 5.041176] ima: No TPM chip found, activating TPM-bypass! [ 5.054663] ima: Allocated hash algorithm: sha1 [ 5.063849] ima: No architecture policies found [ 5.070940] evm: Initialising EVM extended attributes: [ 5.072849] evm: security.selinux [ 5.074133] evm: security.ima [ 5.075253] evm: security.capability [ 5.076523] evm: HMAC attrs: 0x1 [ 5.081801] rtc_cmos 00:05: setting system clock to 2025-08-05 14:48:33 UTC (1754405313) [ 5.120312] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 5.134067] debug: unmapping init [mem 0xffffffffb3f82000-0xffffffffb4258fff] [ 5.150097] Write protecting the kernel read-only data: 28672k [ 5.161721] debug: unmapping init [mem 0xffffffffb2603000-0xffffffffb27fffff] [ 5.171821] debug: unmapping init [mem 0xffffffffb2f14000-0xffffffffb2ffffff] [ 5.297665] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 5.308608] systemd[1]: Detected virtualization kvm. [ 5.311863] systemd[1]: Detected architecture x86-64. [ 5.313816] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.357402] systemd[1]: No hostname configured. [ 5.358911] systemd[1]: Set hostname to . [ 5.367831] random: systemd: uninitialized urandom read (16 bytes read) [ 5.376247] systemd[1]: Initializing machine ID from random generator. [ 5.601153] random: ln: uninitialized urandom read (6 bytes read) [ 6.030689] random: systemd: uninitialized urandom read (16 bytes read) [ 6.036809] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 6.050446] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 6.062440] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. 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... [ 8.337158] device-mapper: uevent: version 1.0.3 [ 8.342946] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 10.699312] virtio_net virtio0 ens2: renamed from eth0 [ 10.757882] random: fast init done [ 10.914363] scsi host0: ata_piix [ 10.970821] scsi host1: ata_piix [ 10.972127] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.987596] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 16.595697] random: crng init done [ 16.597771] random: 7 urandom warning(s) missed due to ratelimiting [ 17.677699] dracut-initqueue[585]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 19.315718] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ 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 Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 21.431737] printk: systemd: 26 output lines suppressed due to ratelimiting [ 22.014726] SELinux: Disabled at runtime. [ 22.122965] 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) [ 22.140377] systemd[1]: Detected virtualization kvm. [ 22.145633] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 23.357957] systemd[1]: initrd-switch-root.service: Succeeded. [ 23.362557] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 23.369151] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 23.373905] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 23.381282] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 23.455031] systemd[1]: Starting Journal Service... Starting Journal Service... [ 23.477696] systemd[1]: Activating swap /dev/disk/by-label/SWAP... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ 23.600618] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Initrd Root File System. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Journal Service. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 25.018312] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 26.290153] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.608456] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 27.874648] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 28.064973] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit)[ 31.494414] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit)[ 32.108257] NFS: Registering the id_resolver key type [ 32.109984] Key type id_resolver registered [ 32.112637] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (8s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ 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 Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg454-client login: [ 64.684813] libcfs: loading out-of-tree module taints kernel. [ 64.744357] Key type ._llcrypt registered [ 64.748355] Key type .llcrypt registered [ 65.065703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 65.077328] alg: No test for adler32 (adler32-zlib) [ 66.147768] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 66.552089] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 68.167164] Key type lgssc registered [ 68.717808] Lustre: Echo OBD driver; http://www.lustre.org/ [ 123.105916] Lustre: Mounted lustre-client [ 125.614359] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 139.661819] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing check_logdir /tmp/testlogs/ [ 141.336117] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing yml_node [ 143.509140] Lustre: DEBUG MARKER: Client: 2.16.57.28 [ 144.572423] Lustre: DEBUG MARKER: MDS: 2.16.57.28 [ 145.548077] Lustre: DEBUG MARKER: OSS: 2.16.57.28 [ 146.194220] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Aug 5 10:50:54 EDT 2025 [ 148.959245] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 24s idle [ 152.446044] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 153.065743] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 153.808781] Lustre: DEBUG MARKER: === sanityn: start setup 10:51:01 (1754405461) === [ 154.124378] Lustre: Mounted lustre-client [ 155.788987] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing check_config_client /mnt/lustre [ 162.915531] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 166.860587] Lustre: DEBUG MARKER: === sanityn: finish setup 10:51:14 (1754405474) === [ 167.883888] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 10:51:15 (1754405475) [ 171.539260] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 10:51:19 (1754405479) [ 174.422971] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 10:51:22 (1754405482) [ 177.130837] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 10:51:24 (1754405484) [ 180.064768] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 10:51:27 (1754405487) [ 182.735117] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 10:51:30 (1754405490) [ 185.504027] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 10:51:33 (1754405493) [ 188.322671] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 10:51:36 (1754405496) [ 188.994669] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 189.748109] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 10:51:37 (1754405497) [ 192.603204] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 10:51:40 (1754405500) [ 195.167898] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 10:51:43 (1754405503) [ 198.838705] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 10:51:46 (1754405506) [ 201.533219] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 10:51:49 (1754405509) [ 204.210643] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 10:51:52 (1754405512) [ 206.710056] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 10:51:54 (1754405514) [ 209.155769] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 10:51:57 (1754405517) [ 211.787831] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 10:51:59 (1754405519) [ 214.169843] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 10:52:02 (1754405522) [ 216.876739] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 10:52:04 (1754405524) [ 219.248292] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 10:52:07 (1754405527) [ 221.890025] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 10:52:09 (1754405529) [ 222.073404] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 308.084093] hrtimer: interrupt took 6081150 ns [ 417.207372] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 10:55:24 (1754405724) [ 424.788731] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 10:55:32 (1754405732) [ 429.971578] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 10:55:37 (1754405737) [ 435.431043] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 10:55:42 (1754405742) [ 440.391244] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 10:55:47 (1754405747) [ 445.345503] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 10:55:52 (1754405752) [ 446.678495] Lustre: DEBUG MARKER: chmod [ 451.110215] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 10:55:58 (1754405758) [ 477.793589] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 488.958875] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 10:56:36 (1754405796) [ 529.863385] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 10:57:17 (1754405837) [ 552.057633] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 10:57:39 (1754405859) [ 553.591300] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 554.483838] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 10:57:42 (1754405862) [ 586.045457] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 10:58:13 (1754405893) [ 590.444823] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 10:58:18 (1754405898) [ 591.121587] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.178378] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.228618] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.293306] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.344741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.392921] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.434052] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.485059] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.533905] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.574985] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.616527] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.660928] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.700375] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.754395] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.810163] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.892555] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 591.959568] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.014581] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.055685] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.107150] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.159200] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.200516] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.255836] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.320808] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.381814] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.459855] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.515553] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.568370] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.623682] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.670519] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.704886] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.745832] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.789390] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.834817] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.886591] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.930231] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 592.984779] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.035421] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.093247] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.153457] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.203753] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.245644] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.298322] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.346073] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.385486] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.440270] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.497898] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.557186] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.618339] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.659910] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.725853] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.771588] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.834808] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.888859] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.936185] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 593.984820] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.029777] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.069585] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.119077] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.170914] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.218461] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.266480] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.317755] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.375466] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.423901] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.477955] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.531515] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.573747] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.616555] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.653730] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.689471] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.742467] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.777318] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.811447] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.859796] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.908465] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 594.966836] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.011206] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.070902] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.115060] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.159899] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.198281] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.235775] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.289529] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.331599] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.377501] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.433435] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.507091] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.574433] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.625327] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.677043] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.778146] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.833263] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.886417] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.941132] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 595.985876] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.032893] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.087547] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.153350] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.203802] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.250355] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.319345] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.383193] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.457086] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.527779] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.601266] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.678661] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.742685] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.826801] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.899865] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 596.974257] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.055716] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.104274] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.174195] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.234793] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.280307] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.338892] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.386895] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.428552] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.470170] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.521700] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.589924] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.676323] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.716741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.758529] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.803131] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.862725] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.901808] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.949511] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 597.994856] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.042916] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.089829] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.157343] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.211618] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.261228] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.305503] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.343194] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.385144] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.431457] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.478931] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.528052] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.588835] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.622530] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.666962] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.724995] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.781353] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.884736] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 598.950704] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.015643] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.066778] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.125718] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.190549] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.243140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.289836] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.344248] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.393099] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.455483] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.516939] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.576869] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.643735] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.691362] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.738275] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.778578] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.825654] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.890982] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.942554] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 599.998844] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.048968] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.097099] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.141821] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.187365] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.242736] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.291869] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.353450] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.400582] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.434261] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.478800] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.513989] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.548854] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.598538] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.641457] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.694173] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.733272] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.791306] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.847440] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.884449] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.925422] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 600.972836] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.028069] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.076309] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.120984] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.160842] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.218569] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.273905] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.340731] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.392770] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.439063] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.484881] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.526857] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.567424] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.612071] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.654944] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.711093] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.765741] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.819602] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.861109] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.896801] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.936556] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 601.973100] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.013751] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.051190] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.085252] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.114239] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.154330] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.200761] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.246039] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.288847] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.321491] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.366093] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.399569] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.431158] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.469501] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.519194] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.567480] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.601320] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.647827] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.690326] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.743946] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.793717] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.849502] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.899215] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 602.951252] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.005290] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.053904] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.111583] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.150487] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.198945] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.250072] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.308140] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.357144] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.414258] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.457746] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.500116] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.547304] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.604245] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.664610] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.737733] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.827836] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.893670] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.930212] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 603.969869] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.007916] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.052877] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.114379] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.158132] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.209348] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.258153] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.297197] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.350385] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.385064] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.423552] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.484139] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.547333] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.589784] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.649270] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.700227] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.751583] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.797743] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.852102] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.905921] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 604.962670] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.006577] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.052990] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.117375] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.182437] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.225723] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.284602] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.336707] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.392149] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.456581] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.508332] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.560444] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.616803] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.669875] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.735902] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.790637] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.839930] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.884532] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.924871] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 605.969463] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.033737] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.094553] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.149571] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.225914] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.263057] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.317509] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.355383] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.406675] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.452076] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.496285] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.547638] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.592842] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.632832] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.678472] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.735602] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.789775] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.844440] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.886668] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.950316] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 606.994930] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.039267] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.085618] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.131170] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.186397] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.250896] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.328843] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.376440] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.426884] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.483420] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.531546] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.601147] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.654110] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.712375] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.782577] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.850203] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.905858] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 607.970376] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.059971] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.113371] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.181325] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.259965] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.318623] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.370970] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.433908] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.510186] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.581928] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.652362] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.737198] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.785733] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.839647] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.888778] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.925098] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 608.971617] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.021752] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.087896] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.139429] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.191200] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.233434] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.282160] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.339875] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.409717] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.449126] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.487386] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.532992] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.594721] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.662345] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.730301] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.760263] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: disconnect after 23s idle [ 609.762357] Lustre: Skipped 1 previous similar message [ 609.784379] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.838795] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.900667] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 609.969513] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.048954] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.103835] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.149505] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.198622] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.256269] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.325818] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.375264] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.438574] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.498736] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 610.564325] rw_seq_cst_vs_d (29474): drop_caches: 3 [ 614.880780] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 24s idle [ 616.196721] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 10:58:43 (1754405923) [ 616.657859] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 616.750821] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 616.785534] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 616.817183] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 616.888321] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.027904] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.078389] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.137568] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.202906] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.266717] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.380238] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.425797] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.484210] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.644179] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.717857] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.800128] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 617.902209] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.046424] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.178393] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.226397] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.415854] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.534333] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.631782] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 618.806321] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.056411] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.112614] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.223329] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.250748] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.349156] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.387774] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.544835] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.619076] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.652597] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.727331] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.849979] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 619.908805] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.003463] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.137154] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.217467] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.312862] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.399711] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.493491] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.527671] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.708682] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 620.820282] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.039664] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.169302] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.274794] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.373412] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.447943] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.561314] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.646212] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.752207] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.790714] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.883570] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 621.932638] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.003955] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.030290] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.145058] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.312385] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.438799] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.642562] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.725560] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.761456] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 622.880495] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.127811] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.210155] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.245506] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.445271] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.573759] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.648092] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.737327] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.772652] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.867612] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 623.971163] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.000668] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.131558] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.190569] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.315742] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.556144] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.581517] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.691811] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.777082] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.818438] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 624.963197] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.118409] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.156276] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.282484] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.380561] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.514585] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.587186] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.656361] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.833115] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 625.935574] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.081264] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.200937] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.258584] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.291488] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.386700] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.563270] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.661248] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.797932] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.832502] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.865838] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 626.959889] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.007652] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.044574] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.171698] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.275859] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.304796] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.361330] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.397819] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.701948] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.822159] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 627.952555] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.118276] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.165320] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.254966] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.510327] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.575691] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.664371] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.787536] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.891421] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 628.968488] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.054942] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.100822] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.191891] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.221538] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.250350] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.320563] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.560888] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.642514] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.759977] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.798546] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.834208] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.862874] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.942165] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 629.986109] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.030976] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.114449] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.256980] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.320218] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.417493] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.468549] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.566263] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.664883] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.869078] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 630.916404] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.019667] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.077506] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.123510] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.241357] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.279359] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.341298] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.497372] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.593331] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.647506] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.689108] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.928259] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 631.955877] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.087964] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.220865] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.369257] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.400103] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.474130] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.578485] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.700932] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.818315] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.891782] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.923890] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 632.952306] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.094678] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.131185] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.261726] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.294086] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.342424] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.403850] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.462725] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.602776] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.775586] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 633.844118] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.136808] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.173200] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.286781] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.324739] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.426327] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.496215] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.530454] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.669127] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.700827] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.727224] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 634.885478] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.037830] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.063672] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.150639] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.347442] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.359191] Lustre: lustre-OST0001-osc-ffff8edd5878b000: disconnect after 23s idle [ 635.598096] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.637254] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.727292] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.838044] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 635.896172] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 636.008150] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 636.038084] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 636.104263] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 636.348810] rw_seq_cst_vs_d (30051): drop_caches: 3 [ 641.637899] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 10:59:09 (1754405949) [ 646.008426] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 10:59:13 (1754405953) [ 649.949396] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 10:59:17 (1754405957) [ 673.467916] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 10:59:40 (1754405980) [ 673.786110] bash (32498): drop_caches: 3 [ 676.910935] bash (32498): drop_caches: 3 [ 680.378487] bash (32498): drop_caches: 3 [ 683.447459] bash (32498): drop_caches: 3 [ 686.536273] bash (32498): drop_caches: 3 [ 689.613115] bash (32498): drop_caches: 3 [ 692.686239] bash (32498): drop_caches: 3 [ 695.775910] bash (32498): drop_caches: 3 [ 698.868949] bash (32498): drop_caches: 3 [ 702.872538] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 11:00:10 (1754406010) [ 709.853865] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 11:00:17 (1754406017) [ 739.531165] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 11:00:47 (1754406047) [ 741.044504] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 742.152672] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 11:00:49 (1754406049) [ 746.505789] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 11:00:54 (1754406054) [ 750.719569] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 11:00:58 (1754406058) [ 753.119262] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: disconnect after 20s idle [ 753.121613] Lustre: Skipped 1 previous similar message [ 763.360139] Lustre: lustre-OST0001-osc-ffff8edd5878b000: disconnect after 21s idle [ 816.298584] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 11:02:03 (1754406123) [ 820.052228] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 11:02:07 (1754406127) [ 823.112598] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 11:02:10 (1754406130) [ 827.412597] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 11:02:14 (1754406134) [ 828.270902] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 829.227906] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 11:02:16 (1754406136) [ 833.648428] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 11:02:21 (1754406141) [ 839.291471] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 11:02:26 (1754406146) [ 843.591930] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 844.454458] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 11:02:32 (1754406152) [ 849.809491] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 11:02:37 (1754406157) [ 850.014379] Lustre: *** cfs_fail_loc=314, val=0*** [ 851.042368] Lustre: *** cfs_fail_loc=314, val=0*** [ 851.044386] Lustre: Skipped 2 previous similar messages [ 854.379992] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 11:02:42 (1754406162) [ 864.767145] Lustre: *** cfs_fail_loc=314, val=0*** [ 864.802787] LustreError: lustre-OST0000-osc-ffff8edd4a3ff000: operation ldlm_enqueue to node 192.168.204.154@tcp failed: rc = -107 [ 864.807045] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 864.819263] LustreError: lustre-OST0000-osc-ffff8edd4a3ff000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 864.828052] LustreError: 41733:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8edd4a3ff000: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8edd44da0700) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 864.836422] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: Connection restored to (at 192.168.204.154@tcp) [ 868.434825] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 11:02:56 (1754406176) [ 868.613234] LustreError: 42315:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 871.631130] LustreError: 42315:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 875.322503] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 11:03:02 (1754406182) [ 880.081753] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 11:03:07 (1754406187) [ 884.841942] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 885.625488] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 11:03:13 (1754406193) [ 886.491840] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 887.442586] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 11:03:15 (1754406195) [ 888.223504] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 889.083959] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 11:03:16 (1754406196) [ 889.841359] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 890.652124] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 11:03:18 (1754406198) [ 891.370679] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 892.190417] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 11:03:19 (1754406199) [ 946.619510] Lustre: lustre-OST0000-osc-ffff8edd5878b000: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 946.638490] LustreError: lustre-OST0000-osc-ffff8edd4a3ff000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 946.645958] LustreError: lustre-OST0000-osc-ffff8edd5878b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 946.647080] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: Connection restored to (at 192.168.204.154@tcp) [ 961.981303] Lustre: lustre-OST0001-osc-ffff8edd5878b000: Connection to lustre-OST0001 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 961.988732] Lustre: Skipped 1 previous similar message [ 961.999077] LustreError: lustre-OST0001-osc-ffff8edd5878b000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 962.005614] Lustre: lustre-OST0001-osc-ffff8edd5878b000: Connection restored to (at 192.168.204.154@tcp) [ 962.011468] Lustre: Skipped 1 previous similar message [ 968.161267] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 21s idle [ 968.166279] Lustre: Skipped 2 previous similar messages [ 974.564508] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 975.302742] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid in IDLE state after 0 sec [ 977.029663] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 977.688366] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid in FULL state after 0 sec [ 980.037694] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 980.700480] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid in IDLE state after 0 sec [ 982.360245] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 983.094976] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid in FULL state after 0 sec [ 987.458273] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 988.146155] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8edd4a3ff000.ost_server_uuid in IDLE state after 0 sec [ 989.914187] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid 50 [ 990.760321] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8edd4a3ff000.ost_server_uuid in FULL state after 0 sec [ 991.685784] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 11:04:59 (1754406299) [ 993.107777] Lustre: DEBUG MARKER: Race attempt 0 [ 995.029944] Lustre: DEBUG MARKER: Wait for 49013 49108 for 60 sec... [ 1058.231959] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 11:06:05 (1754406365) [ 1064.004297] Lustre: DEBUG MARKER: start test - cycle (0) [ 1085.308039] Lustre: DEBUG MARKER: start test - cycle (1) [ 1106.136564] Lustre: DEBUG MARKER: start test - cycle (2) [ 1126.336899] Lustre: DEBUG MARKER: start test - cycle (3) [ 1146.159065] Lustre: DEBUG MARKER: start test - cycle (4) [ 1167.813980] Lustre: DEBUG MARKER: start test - cycle (5) [ 1187.996506] Lustre: DEBUG MARKER: start test - cycle (6) [ 1208.685813] Lustre: DEBUG MARKER: start test - cycle (7) [ 1229.005838] Lustre: DEBUG MARKER: start test - cycle (8) [ 1249.255126] Lustre: DEBUG MARKER: start test - cycle (9) [ 1269.722629] Lustre: DEBUG MARKER: start test - cycle (10) [ 1293.152445] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 11:10:00 (1754406600) [ 1295.839283] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: disconnect after 20s idle [ 1295.842423] Lustre: Skipped 2 previous similar messages [ 1321.127798] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 11:10:29 (1754406629) [ 1323.873351] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 11:10:31 (1754406631) [ 1327.502992] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 11:10:35 (1754406635) [ 1331.154371] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 11:10:38 (1754406638) [ 1331.266323] Lustre: *** cfs_fail_loc=411, val=0*** [ 1334.031835] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1334.792906] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 11:10:42 (1754406642) [ 1344.590045] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 11:10:52 (1754406652) [ 1353.786045] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 11:11:01 (1754406661) [ 1362.399192] Lustre: lustre-OST0001-osc-ffff8edd5878b000: disconnect after 20s idle [ 1362.403036] Lustre: Skipped 2 previous similar messages [ 1362.887797] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 11:11:10 (1754406670) [ 1371.954471] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 11:11:19 (1754406679) [ 1378.714066] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 11:11:26 (1754406686) [ 1384.878532] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 11:11:32 (1754406692) [ 1391.028541] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 11:11:38 (1754406698) [ 1397.155091] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 11:11:45 (1754406705) [ 1403.239762] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 11:11:51 (1754406711) [ 1409.482190] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 11:11:57 (1754406717) [ 1415.584413] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 11:12:03 (1754406723) [ 1421.650556] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 11:12:09 (1754406729) [ 2043.359246] Lustre: lustre-OST0000-osc-ffff8edd4a3ff000: disconnect after 21s idle [ 2043.363276] Lustre: Skipped 3 previous similar messages [ 2172.611151] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 11:24:40 (1754407480) [ 2178.239274] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 11:24:46 (1754407486) [ 2183.917052] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 11:24:51 (1754407491) [ 2189.530845] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 11:24:57 (1754407497) [ 2194.928200] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 11:25:02 (1754407502) [ 2200.666705] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 11:25:08 (1754407508) [ 2206.637208] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 11:25:14 (1754407514) [ 2212.551081] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 11:25:20 (1754407520) [ 2218.209186] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 11:25:26 (1754407526) [ 2240.292484] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 11:25:48 (1754407548) [ 2246.158486] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 11:25:54 (1754407554) [ 2251.933871] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 11:25:59 (1754407559) [ 2257.600454] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 11:26:05 (1754407565) [ 2263.199308] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 11:26:11 (1754407571) [ 2268.659728] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 11:26:16 (1754407576) [ 2274.231785] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 11:26:22 (1754407582) [ 2279.854188] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 11:26:27 (1754407587) [ 2280.384667] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2281.011174] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 11:26:28 (1754407588) [ 2304.479266] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 23s idle [ 2304.482352] Lustre: Skipped 4 previous similar messages [ 2332.778394] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 11:27:20 (1754407640) [ 2840.361244] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 11:35:48 (1754408148) [ 2846.652854] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 11:35:54 (1754408154) [ 2852.825675] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 11:36:00 (1754408160) [ 2859.086504] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 11:36:06 (1754408166) [ 2864.764411] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 11:36:12 (1754408172) [ 2870.828379] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 11:36:18 (1754408178) [ 2877.273264] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 11:36:25 (1754408185) [ 2883.967653] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 11:36:31 (1754408191) [ 2889.973142] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 11:36:37 (1754408197) [ 2890.596644] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2891.308209] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 11:36:39 (1754408199) [ 2908.639118] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 24s idle [ 2908.643148] Lustre: Skipped 1 previous similar message [ 2929.910313] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 11:37:17 (1754408237) [ 2935.940818] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 11:37:23 (1754408243) [ 2941.777067] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 11:37:29 (1754408249) [ 2947.667238] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 11:37:35 (1754408255) [ 2953.812725] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 11:37:41 (1754408261) [ 2959.829490] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 11:37:47 (1754408267) [ 2965.570880] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 11:37:53 (1754408273) [ 2970.877275] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 11:37:58 (1754408278) [ 2971.452216] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2972.103453] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 11:37:59 (1754408279) [ 3455.894885] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 11:46:03 (1754408763) [ 3461.189926] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 11:46:09 (1754408769) [ 3466.464819] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 11:46:14 (1754408774) [ 3471.733547] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 11:46:19 (1754408779) [ 3477.141672] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 11:46:25 (1754408785) [ 3482.582610] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 11:46:30 (1754408790) [ 3488.030852] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 11:46:35 (1754408795) [ 3493.432548] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 11:46:41 (1754408801) [ 3498.660407] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 11:46:46 (1754408806) [ 3499.122703] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3499.660034] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 11:46:47 (1754408807) [ 3500.132578] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3500.674991] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 11:46:48 (1754408808) [ 3501.200786] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3501.755228] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 11:46:49 (1754408809) [ 3502.252786] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3502.819339] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 11:46:50 (1754408810) [ 3503.299946] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3503.834680] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 11:46:51 (1754408811) [ 3504.311713] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3504.840128] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 11:46:52 (1754408812) [ 3505.330775] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3505.874158] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 11:46:53 (1754408813) [ 3506.370250] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3506.929423] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 11:46:54 (1754408814) [ 3507.011611] LustreError: 5506:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3509.095109] LustreError: 5506:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3514.088983] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 11:47:02 (1754408822) [ 3518.349279] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 11:47:06 (1754408826) [ 3518.422688] LustreError: 260378:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3522.479118] LustreError: 260378:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3522.486042] LustreError: 260378:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3526.543114] LustreError: 260378:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3526.556522] LustreError: 260385:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3530.615170] LustreError: 260385:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3532.911484] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 11:47:20 (1754408840) [ 3539.633362] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 11:47:27 (1754408847) [ 3542.721848] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 11:47:30 (1754408850) [ 3546.875397] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 11:47:34 (1754408854) [ 3558.879211] Lustre: lustre-OST0000-osc-ffff8edd5878b000: disconnect after 24s idle [ 3558.882957] Lustre: Skipped 3 previous similar messages [ 3570.940450] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 11:47:58 (1754408878) [ 3578.629598] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 11:48:06 (1754408886) [ 3586.338150] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 11:48:14 (1754408894) [ 3599.426451] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 11:48:27 (1754408907) [ 3609.130092] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 11:48:37 (1754408917) [ 3609.627411] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3610.178240] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 11:48:38 (1754408918) [ 3623.094352] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 11:48:51 (1754408931) [ 3637.092453] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 11:49:05 (1754408945) [ 3666.886700] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 11:49:34 (1754408974) [ 3706.878935] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 11:50:14 (1754409014) [ 3709.188186] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3711.618149] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 11:50:19 (1754409019) [ 3714.264965] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 11:50:22 (1754409022) [ 3714.902260] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3715.729048] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 11:50:23 (1754409023) [ 3716.534093] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3717.333417] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 11:50:25 (1754409025) [ 3717.977208] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3718.709438] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 11:50:26 (1754409026) [ 3719.386516] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3720.144288] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 11:50:28 (1754409028) [ 3722.842635] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 11:50:30 (1754409030) [ 3725.041323] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 11:50:33 (1754409033) [ 3728.133983] LustreError: lustre-MDT0000-mdc-ffff8edd5878b000: operation ldlm_enqueue to node 192.168.204.154@tcp failed: rc = -35 [ 3728.136691] LustreError: Skipped 1 previous similar message [ 3731.187905] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 11:50:39 (1754409039) [ 3731.323066] LustreError: 2353:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3733.375108] LustreError: 2353:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3738.393103] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 11:50:46 (1754409046) [ 3756.810824] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 11:51:04 (1754409064) [ 3759.904775] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 11:51:07 (1754409067) [ 3764.110889] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 11:51:12 (1754409072) [ 3769.156960] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 11:51:17 (1754409077) [ 3774.030371] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 11:51:21 (1754409081) [ 3781.756229] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 11:51:29 (1754409089) [ 3790.005760] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 11:51:37 (1754409097) [ 3793.775979] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 11:51:41 (1754409101) [ 3797.865976] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 11:51:45 (1754409105) [ 3806.085383] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 11:51:53 (1754409113) [ 3853.965867] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 11:52:41 (1754409161) [ 3986.731503] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 11:54:54 (1754409294) [ 4114.919759] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 11:57:02 (1754409422) [ 4178.399221] Lustre: lustre-OST0001-osc-ffff8edd5878b000: disconnect after 20s idle [ 4178.401510] Lustre: Skipped 10 previous similar messages [ 4470.424940] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 12:02:58 (1754409778) [ 4473.631887] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 12:03:01 (1754409781) [ 4524.520157] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 12:03:52 (1754409832) [ 4591.219670] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 12:04:59 (1754409899) [ 4595.017666] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 12:05:02 (1754409902) [ 4633.844733] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 12:05:41 (1754409941) [ 4645.859192] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 12:05:53 (1754409953) [ 4686.573901] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 12:06:34 (1754409994) [ 4689.391676] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 12:06:37 (1754409997) [ 4702.010948] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 12:06:49 (1754410009) [ 4702.535661] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 4703.054436] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 12:06:51 (1754410011) [ 4703.543157] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 4704.089187] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 12:06:52 (1754410012) [ 4704.577469] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 4705.075492] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 12:06:53 (1754410013) [ 4705.522272] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 4706.007259] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 12:06:54 (1754410014) [ 4706.454611] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4706.991480] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 12:06:54 (1754410014) [ 4729.849474] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 12:07:17 (1754410037) [ 4731.755160] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 12:07:19 (1754410039) [ 4732.221900] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 4732.764560] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 12:07:20 (1754410040) [ 4739.986709] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 12:07:27 (1754410047) [ 4742.532834] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 12:07:30 (1754410050) [ 4742.982319] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 4743.466213] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 12:07:31 (1754410051) [ 4743.898423] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 4744.393309] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 12:07:32 (1754410052) [ 4744.874264] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 4745.359632] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 12:07:33 (1754410053) [ 4753.978214] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 12:07:41 (1754410061) [ 4754.066350] Lustre: DEBUG MARKER: write [ 4754.082938] LustreError: 31860:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 4756.087646] Lustre: DEBUG MARKER: kill 319333 [ 4756.089987] LustreError: 319333:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 4759.183127] LustreError: 31860:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 4762.127122] LustreError: 319333:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 4764.214302] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 12:07:52 (1754410072) [ 4766.365425] LustreError: 319938:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 4776.455101] LustreError: 319938:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 4778.695159] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 12:08:06 (1754410086) [ 4778.784787] LustreError: 320519:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 4780.871139] LustreError: 320519:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 4786.974561] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 12:08:14 (1754410094) [ 4787.529798] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 4788.178090] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 12:08:16 (1754410096) [ 4790.424093] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 12:08:18 (1754410098) [ 4792.742775] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 12:08:20 (1754410100) [ 4795.074358] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 12:08:23 (1754410103) [ 4797.488357] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 12:08:25 (1754410105) [ 4799.824662] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 12:08:27 (1754410107) [ 4802.152962] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 12:08:30 (1754410110) [ 4805.318851] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 4805.839685] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 12:08:33 (1754410113) [ 4806.431449] Lustre: *** cfs_fail_loc=415, val=0*** [ 4813.016444] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 12:08:40 (1754410120) [ 4813.576208] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 4814.107263] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 12:08:42 (1754410122) [ 4814.167534] LustreError: 5516:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4814.170297] LustreError: 5516:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 4819.263103] LustreError: 5981:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 4819.264897] LustreError: 5981:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 4829.455111] LustreError: 5517:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 4829.457468] LustreError: 5517:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 4831.697956] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 12:08:59 (1754410139) [ 4832.197761] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 4832.755285] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 12:09:00 (1754410140) [ 4834.976982] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 12:09:02 (1754410142) [ 4837.132321] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 12:09:05 (1754410145) [ 4841.381573] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 12:09:09 (1754410149) [ 4849.794891] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 12:09:17 (1754410157) [ 4849.910221] LustreError: 2352:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 4849.912820] LustreError: 2352:0:(osc_request.c:2943:osc_build_rpc()) Skipped 6 previous similar messages [ 4853.967215] LustreError: 2352:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 4853.969136] LustreError: 2352:0:(osc_request.c:2943:osc_build_rpc()) Skipped 2 previous similar messages [ 4856.283861] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 12:09:24 (1754410164) [ 4857.018652] LustreError: 330453:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd5878b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4857.024194] LustreError: 330453:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4857.043117] Lustre: Unmounted lustre-client [ 4857.707722] LustreError: 330473:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd4a3ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4857.711489] LustreError: 330473:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 4857.715372] LustreError: 330473:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4857.717163] LustreError: 330473:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4857.729086] Lustre: Unmounted lustre-client [ 4858.230628] Lustre: DEBUG MARKER: Iteration 1 [ 4858.392339] LustreError: 330633:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4858.392456] LustreError: 330634:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4858.399219] LustreError: 330633:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4858.450875] Lustre: Mounted lustre-client [ 4858.451913] Lustre: Skipped 1 previous similar message [ 4858.900754] LustreError: 330740:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd4a0ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4858.904403] LustreError: 330740:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 4858.909664] LustreError: 330741:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4858.912263] LustreError: 330741:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4858.929545] Lustre: Unmounted lustre-client [ 4860.012808] Key type lgssc unregistered [ 4860.137627] LNet: 330982:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4860.140458] LNetError: 330982:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4860.151763] LNet: Removed LNI 192.168.204.54@tcp [ 4860.469115] Key type .llcrypt unregistered [ 4860.470778] Key type ._llcrypt unregistered [ 4860.905278] Key type ._llcrypt registered [ 4860.907308] Key type .llcrypt registered [ 4861.209710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4861.215029] alg: No test for adler32 (adler32-zlib) [ 4862.222269] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4862.538399] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4864.159201] Key type lgssc registered [ 4864.754118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4869.137096] Lustre: DEBUG MARKER: Iteration 2 [ 4869.246452] LustreError: 331801:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4869.246493] LustreError: 331802:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4869.250985] LustreError: 331801:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4870.298983] Lustre: Mounted lustre-client [ 4870.822593] LustreError: 331906:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50017800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4870.827726] LustreError: 331906:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4870.851823] Lustre: Unmounted lustre-client [ 4870.853210] Lustre: Skipped 1 previous similar message [ 4871.914446] Key type lgssc unregistered [ 4872.034315] LNet: 332148:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4872.036599] LNetError: 332148:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4872.045819] LNet: Removed LNI 192.168.204.54@tcp [ 4872.301145] Key type .llcrypt unregistered [ 4872.302380] Key type ._llcrypt unregistered [ 4872.608219] Key type ._llcrypt registered [ 4872.613118] Key type .llcrypt registered [ 4872.866479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4872.871942] alg: No test for adler32 (adler32-zlib) [ 4873.722059] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4873.807166] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4875.391124] Key type lgssc registered [ 4875.798574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4879.341427] Lustre: DEBUG MARKER: Iteration 3 [ 4879.442279] LustreError: 332966:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4879.444651] LustreError: 332967:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4879.446639] LustreError: 332966:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 4880.496387] Lustre: Mounted lustre-client [ 4880.971473] LustreError: 333073:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd8073d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4880.974505] LustreError: 333072:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4880.975246] LustreError: 333073:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4880.992108] Lustre: Unmounted lustre-client [ 4882.021390] Key type lgssc unregistered [ 4882.146598] LNet: 333314:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4882.148834] LNetError: 333314:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4882.156784] LNet: Removed LNI 192.168.204.54@tcp [ 4882.398090] Key type .llcrypt unregistered [ 4882.399365] Key type ._llcrypt unregistered [ 4882.670877] Key type ._llcrypt registered [ 4882.672490] Key type .llcrypt registered [ 4882.844787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4882.849811] alg: No test for adler32 (adler32-zlib) [ 4883.706446] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4883.784170] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4885.367152] Key type lgssc registered [ 4885.743185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4889.367292] Lustre: DEBUG MARKER: Iteration 4 [ 4889.478710] LustreError: 334131:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4889.478760] LustreError: 334132:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4889.484668] LustreError: 334131:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4890.535314] Lustre: Mounted lustre-client [ 4891.018363] LustreError: 334239:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd580be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4891.020586] LustreError: 334240:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4891.022161] LustreError: 334239:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4891.028175] LustreError: 334240:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4891.041422] Lustre: Unmounted lustre-client [ 4892.083047] Key type lgssc unregistered [ 4892.206530] LNet: 334480:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4892.208821] LNetError: 334480:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4892.215729] LNet: Removed LNI 192.168.204.54@tcp [ 4892.483157] Key type .llcrypt unregistered [ 4892.484186] Key type ._llcrypt unregistered [ 4892.774298] Key type ._llcrypt registered [ 4892.775516] Key type .llcrypt registered [ 4892.961350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4892.967850] alg: No test for adler32 (adler32-zlib) [ 4893.831781] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4893.921247] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4895.511128] Key type lgssc registered [ 4895.878763] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4899.451244] Lustre: DEBUG MARKER: Iteration 5 [ 4899.555659] LustreError: 335298:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4899.555699] LustreError: 335299:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4899.561690] LustreError: 335298:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4900.612329] Lustre: Mounted lustre-client [ 4901.090133] LustreError: 335400:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd67ef3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4901.091983] LustreError: 335401:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4901.094467] LustreError: 335400:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4901.108072] Lustre: Unmounted lustre-client [ 4902.129891] Key type lgssc unregistered [ 4902.256492] LNet: 335643:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4902.258710] LNetError: 335643:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4902.268743] LNet: Removed LNI 192.168.204.54@tcp [ 4902.539128] Key type .llcrypt unregistered [ 4902.540833] Key type ._llcrypt unregistered [ 4902.906650] Key type ._llcrypt registered [ 4902.907717] Key type .llcrypt registered [ 4903.110156] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4903.114904] alg: No test for adler32 (adler32-zlib) [ 4903.990054] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4904.100475] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4905.695206] Key type lgssc registered [ 4906.084233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4909.582889] Lustre: DEBUG MARKER: Iteration 6 [ 4909.688542] LustreError: 336460:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4909.689836] LustreError: 336461:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4909.694590] LustreError: 336460:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4910.748499] Lustre: Mounted lustre-client [ 4911.357736] LustreError: 336568:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd49c81000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4911.364692] LustreError: 336568:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 4911.367520] LustreError: 336568:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4911.378090] Lustre: Unmounted lustre-client [ 4912.434659] Key type lgssc unregistered [ 4912.563440] LNet: 336810:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4912.565959] LNetError: 336810:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4912.573780] LNet: Removed LNI 192.168.204.54@tcp [ 4912.830147] Key type .llcrypt unregistered [ 4912.831197] Key type ._llcrypt unregistered [ 4913.081794] Key type ._llcrypt registered [ 4913.083587] Key type .llcrypt registered [ 4913.276159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4913.283780] alg: No test for adler32 (adler32-zlib) [ 4914.147765] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4914.236596] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4915.839163] Key type lgssc registered [ 4916.253688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4919.770548] Lustre: DEBUG MARKER: Iteration 7 [ 4919.880452] LustreError: 337639:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4919.880484] LustreError: 337640:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4919.887026] LustreError: 337639:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4920.940439] Lustre: Mounted lustre-client [ 4920.941316] Lustre: Skipped 1 previous similar message [ 4921.414448] LustreError: 337747:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4921.417804] LustreError: 337747:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4921.421282] LustreError: 337746:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4921.423567] LustreError: 337746:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4921.443107] Lustre: Unmounted lustre-client [ 4922.500424] Key type lgssc unregistered [ 4922.626495] LNet: 337989:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4922.629336] LNetError: 337989:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4922.638684] LNet: Removed LNI 192.168.204.54@tcp [ 4922.887094] Key type .llcrypt unregistered [ 4922.888163] Key type ._llcrypt unregistered [ 4923.132598] Key type ._llcrypt registered [ 4923.134384] Key type .llcrypt registered [ 4923.366124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4923.372741] alg: No test for adler32 (adler32-zlib) [ 4924.245360] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4924.336381] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4925.927155] Key type lgssc registered [ 4926.346749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4930.059667] Lustre: DEBUG MARKER: Iteration 8 [ 4930.179980] LustreError: 338806:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4930.180079] LustreError: 338807:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4930.184977] LustreError: 338806:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4931.236463] Lustre: Mounted lustre-client [ 4931.237413] Lustre: Skipped 1 previous similar message [ 4931.710731] LustreError: 338910:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd5878b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4931.715339] LustreError: 338910:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4931.717692] LustreError: 338910:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4931.737921] Lustre: Unmounted lustre-client [ 4932.877451] Key type lgssc unregistered [ 4933.022786] LNet: 339152:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4933.025747] LNetError: 339152:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4933.036805] LNet: Removed LNI 192.168.204.54@tcp [ 4933.346119] Key type .llcrypt unregistered [ 4933.347486] Key type ._llcrypt unregistered [ 4933.625959] Key type ._llcrypt registered [ 4933.627201] Key type .llcrypt registered [ 4933.838621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4933.845604] alg: No test for adler32 (adler32-zlib) [ 4934.712165] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4934.809173] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4936.399182] Key type lgssc registered [ 4936.811136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4940.475513] Lustre: DEBUG MARKER: Iteration 9 [ 4940.607363] LustreError: 339970:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4940.607400] LustreError: 339969:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4940.613084] LustreError: 339970:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4941.665720] Lustre: Mounted lustre-client [ 4942.203958] LustreError: 340077:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd7b078800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4942.210662] LustreError: 340077:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4942.248729] Lustre: Unmounted lustre-client [ 4943.250962] Key type lgssc unregistered [ 4943.382316] LNet: 340320:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4943.384465] LNetError: 340320:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4943.396149] LNet: Removed LNI 192.168.204.54@tcp [ 4943.651092] Key type .llcrypt unregistered [ 4943.652190] Key type ._llcrypt unregistered [ 4943.905656] Key type ._llcrypt registered [ 4943.907521] Key type .llcrypt registered [ 4944.122640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4944.129117] alg: No test for adler32 (adler32-zlib) [ 4944.991715] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4945.075450] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4946.655121] Key type lgssc registered [ 4947.088875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4950.537439] Lustre: DEBUG MARKER: Iteration 10 [ 4950.632175] LustreError: 341137:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4950.634621] LustreError: 341138:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4950.637563] LustreError: 341137:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4951.690292] Lustre: Mounted lustre-client [ 4951.691558] Lustre: Skipped 1 previous similar message [ 4952.184988] LustreError: 341247:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd49c81000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4952.188501] LustreError: 341248:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 4952.190191] LustreError: 341247:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4952.206423] Lustre: Unmounted lustre-client [ 4953.380828] Key type lgssc unregistered [ 4953.535131] LNet: 341489:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4953.539163] LNetError: 341489:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4953.551713] LNet: Removed LNI 192.168.204.54@tcp [ 4953.856118] Key type .llcrypt unregistered [ 4953.857348] Key type ._llcrypt unregistered [ 4954.194323] Key type ._llcrypt registered [ 4954.195744] Key type .llcrypt registered [ 4954.405714] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4954.415424] alg: No test for adler32 (adler32-zlib) [ 4955.376758] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4955.559977] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4957.175400] Key type lgssc registered [ 4958.002488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4965.841901] Lustre: DEBUG MARKER: Iteration 11 [ 4966.116933] LustreError: 342307:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4966.116956] LustreError: 342308:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4966.131516] LustreError: 342307:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 4967.282873] Lustre: Mounted lustre-client [ 4968.106293] LustreError: 342408:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd7402b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4968.128619] LustreError: 342408:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4968.159090] Lustre: Unmounted lustre-client [ 4968.164674] Lustre: Skipped 1 previous similar message [ 4969.757370] Key type lgssc unregistered [ 4969.937438] LNet: 342656:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4969.942404] LNetError: 342656:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 4969.956588] LNet: Removed LNI 192.168.204.54@tcp [ 4970.372153] Key type .llcrypt unregistered [ 4970.373956] Key type ._llcrypt unregistered [ 4970.852212] Key type ._llcrypt registered [ 4970.853609] Key type .llcrypt registered [ 4971.171332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4971.180919] alg: No test for adler32 (adler32-zlib) [ 4972.152864] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 4972.285165] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 4973.911167] Key type lgssc registered [ 4975.308362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4993.274481] Lustre: DEBUG MARKER: Iteration 12 [ 4993.917273] LustreError: 343475:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 4993.918238] LustreError: 343476:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 4993.941242] LustreError: 343475:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 4995.368586] Lustre: Mounted lustre-client [ 4995.374434] Lustre: Skipped 1 previous similar message [ 4997.536345] LustreError: 343576:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd7241d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4997.561682] LustreError: 343576:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 4997.641798] Lustre: Unmounted lustre-client [ 5001.969595] Key type lgssc unregistered [ 5002.371725] LNet: 343825:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5002.393290] LNetError: 343825:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5002.421772] LNet: Removed LNI 192.168.204.54@tcp [ 5003.532269] Key type .llcrypt unregistered [ 5003.540508] Key type ._llcrypt unregistered [ 5004.955469] Key type ._llcrypt registered [ 5004.957018] Key type .llcrypt registered [ 5006.163537] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5006.230531] alg: No test for adler32 (adler32-zlib) [ 5007.531406] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5007.971266] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5009.823340] Key type lgssc registered [ 5012.291642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5028.265295] Lustre: DEBUG MARKER: Iteration 13 [ 5028.715496] LustreError: 344636:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5028.753479] LustreError: 344654:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5028.767353] LustreError: 344636:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4951 [ 5030.186418] Lustre: Mounted lustre-client [ 5032.161042] LustreError: 344750:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd7546c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5032.188941] LustreError: 344750:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5032.211525] LustreError: 344750:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5032.230206] LustreError: 344750:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5032.243568] Lustre: Unmounted lustre-client [ 5036.087597] Key type lgssc unregistered [ 5036.507431] LNet: 344999:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5036.528358] LNetError: 344999:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5036.580575] LNet: Removed LNI 192.168.204.54@tcp [ 5037.351160] Key type .llcrypt unregistered [ 5037.355804] Key type ._llcrypt unregistered [ 5038.103434] Key type ._llcrypt registered [ 5038.105406] Key type .llcrypt registered [ 5038.915236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5038.958400] alg: No test for adler32 (adler32-zlib) [ 5040.177640] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5040.523691] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5042.263166] Key type lgssc registered [ 5043.796940] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5053.734340] Lustre: DEBUG MARKER: Iteration 14 [ 5053.984760] LustreError: 345815:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5053.986248] LustreError: 345817:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5054.000867] LustreError: 345815:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5055.190642] Lustre: Mounted lustre-client [ 5056.248892] LustreError: 345922:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd526fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5056.267551] LustreError: 345922:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5056.310192] Lustre: Unmounted lustre-client [ 5056.315606] Lustre: Skipped 1 previous similar message [ 5058.498867] Key type lgssc unregistered [ 5058.731550] LNet: 346168:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5058.737985] LNetError: 346168:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5058.750717] LNet: Removed LNI 192.168.204.54@tcp [ 5059.332159] Key type .llcrypt unregistered [ 5059.333972] Key type ._llcrypt unregistered [ 5060.060568] Key type ._llcrypt registered [ 5060.073301] Key type .llcrypt registered [ 5060.288373] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5060.303712] alg: No test for adler32 (adler32-zlib) [ 5061.308171] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5061.484758] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5063.143195] Key type lgssc registered [ 5064.034526] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5072.116335] Lustre: DEBUG MARKER: Iteration 15 [ 5072.464779] LustreError: 346987:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5072.470283] LustreError: 346995:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5072.474378] LustreError: 346987:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 5073.660842] Lustre: Mounted lustre-client [ 5074.766959] LustreError: 347093:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5074.783694] LustreError: 347093:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5074.838620] Lustre: Unmounted lustre-client [ 5077.050894] Key type lgssc unregistered [ 5077.271954] LNet: 347340:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5077.277025] LNetError: 347340:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5077.289954] LNet: Removed LNI 192.168.204.54@tcp [ 5077.809143] Key type .llcrypt unregistered [ 5077.810841] Key type ._llcrypt unregistered [ 5078.340242] Key type ._llcrypt registered [ 5078.349154] Key type .llcrypt registered [ 5078.490391] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5078.498038] alg: No test for adler32 (adler32-zlib) [ 5079.445399] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5079.642508] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5081.279159] Key type lgssc registered [ 5082.000032] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5089.872581] Lustre: DEBUG MARKER: Iteration 16 [ 5090.063235] LustreError: 348159:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5090.064707] LustreError: 348158:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5090.072407] LustreError: 348159:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5091.216240] Lustre: Mounted lustre-client [ 5092.104890] LustreError: 348266:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd58382000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5092.113411] LustreError: 348266:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5092.142691] Lustre: Unmounted lustre-client [ 5093.927661] Key type lgssc unregistered [ 5094.119602] LNet: 348509:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5094.123571] LNetError: 348509:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5094.137283] LNet: Removed LNI 192.168.204.54@tcp [ 5094.567546] Key type .llcrypt unregistered [ 5094.569360] Key type ._llcrypt unregistered [ 5095.013954] Key type ._llcrypt registered [ 5095.015677] Key type .llcrypt registered [ 5095.287924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5095.303413] alg: No test for adler32 (adler32-zlib) [ 5096.291896] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5096.471889] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5098.151217] Key type lgssc registered [ 5099.134931] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5106.840955] Lustre: DEBUG MARKER: Iteration 17 [ 5107.136164] LustreError: 349328:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5107.136359] LustreError: 349329:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5107.145027] LustreError: 349328:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5109.360356] Lustre: Mounted lustre-client [ 5110.473160] LustreError: 349435:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd80426800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5110.492140] LustreError: 349435:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5110.523151] Lustre: Unmounted lustre-client [ 5110.526941] Lustre: Skipped 1 previous similar message [ 5112.684372] Key type lgssc unregistered [ 5113.004537] LNet: 349677:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5113.008695] LNetError: 349677:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5113.025928] LNet: Removed LNI 192.168.204.54@tcp [ 5113.567504] Key type .llcrypt unregistered [ 5113.571181] Key type ._llcrypt unregistered [ 5114.287332] Key type ._llcrypt registered [ 5114.295064] Key type .llcrypt registered [ 5114.496542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5114.508668] alg: No test for adler32 (adler32-zlib) [ 5115.517621] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5115.706870] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5117.375609] Key type lgssc registered [ 5118.262603] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5127.307157] Lustre: DEBUG MARKER: Iteration 18 [ 5127.629658] LustreError: 350492:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5127.632846] LustreError: 350499:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5127.641423] LustreError: 350492:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5128.930145] Lustre: Mounted lustre-client [ 5129.995214] LustreError: 350598:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd70991800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5130.017286] LustreError: 350598:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5130.051100] Lustre: Unmounted lustre-client [ 5132.389790] Key type lgssc unregistered [ 5132.598303] LNet: 350847:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5132.601648] LNetError: 350847:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5132.613299] LNet: Removed LNI 192.168.204.54@tcp [ 5133.116152] Key type .llcrypt unregistered [ 5133.117856] Key type ._llcrypt unregistered [ 5133.654889] Key type ._llcrypt registered [ 5133.656847] Key type .llcrypt registered [ 5133.987409] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5133.997635] alg: No test for adler32 (adler32-zlib) [ 5135.001883] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5135.159980] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5136.823281] Key type lgssc registered [ 5137.779626] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5146.271431] Lustre: DEBUG MARKER: Iteration 19 [ 5146.478227] LustreError: 351666:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5146.480832] LustreError: 351669:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5146.493113] LustreError: 351666:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5147.668653] Lustre: Mounted lustre-client [ 5147.673835] Lustre: Skipped 1 previous similar message [ 5148.616256] LustreError: 351770:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43b83000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5148.633681] LustreError: 351770:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5148.666855] Lustre: Unmounted lustre-client [ 5150.665909] Key type lgssc unregistered [ 5150.878764] LNet: 352019:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5150.881935] LNetError: 352019:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5150.894886] LNet: Removed LNI 192.168.204.54@tcp [ 5151.427170] Key type .llcrypt unregistered [ 5151.428822] Key type ._llcrypt unregistered [ 5152.010232] Key type ._llcrypt registered [ 5152.013048] Key type .llcrypt registered [ 5152.293878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5152.306630] alg: No test for adler32 (adler32-zlib) [ 5153.290362] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5153.433727] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5155.071186] Key type lgssc registered [ 5155.995476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5163.812446] Lustre: DEBUG MARKER: Iteration 20 [ 5164.073167] LustreError: 352838:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5164.073475] LustreError: 352839:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5164.082422] LustreError: 352838:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5165.276563] Lustre: Mounted lustre-client [ 5166.392579] LustreError: 352944:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd49c86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5166.409413] LustreError: 352944:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5166.429130] Lustre: Unmounted lustre-client [ 5168.498792] Key type lgssc unregistered [ 5168.695853] LNet: 353189:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5168.701238] LNetError: 353189:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5168.717715] LNet: Removed LNI 192.168.204.54@tcp [ 5169.137192] Key type .llcrypt unregistered [ 5169.140692] Key type ._llcrypt unregistered [ 5169.638324] Key type ._llcrypt registered [ 5169.639922] Key type .llcrypt registered [ 5169.860154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5169.870299] alg: No test for adler32 (adler32-zlib) [ 5170.864224] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5171.017477] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5172.650139] Key type lgssc registered [ 5173.419553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5179.705390] Lustre: DEBUG MARKER: Iteration 21 [ 5179.883669] LustreError: 354006:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5179.883774] LustreError: 354007:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5179.892716] LustreError: 354006:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5181.040309] Lustre: Mounted lustre-client [ 5181.980922] LustreError: 354120:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd48d39800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5181.988620] LustreError: 354120:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5181.998188] LustreError: 354120:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5182.002246] LustreError: 354120:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5182.032112] Lustre: Unmounted lustre-client [ 5184.118214] Key type lgssc unregistered [ 5184.302656] LNet: 354360:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5184.309242] LNetError: 354360:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5185.381916] LNet: Removed LNI 192.168.204.54@tcp [ 5185.874929] Key type .llcrypt unregistered [ 5185.876786] Key type ._llcrypt unregistered [ 5186.360342] Key type ._llcrypt registered [ 5186.364447] Key type .llcrypt registered [ 5186.609025] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5186.623971] alg: No test for adler32 (adler32-zlib) [ 5187.631324] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5187.775871] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5189.426157] Key type lgssc registered [ 5190.450873] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5199.751461] Lustre: DEBUG MARKER: Iteration 22 [ 5200.020421] LustreError: 355179:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5200.027884] LustreError: 355180:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5200.033486] LustreError: 355179:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 5201.257622] Lustre: Mounted lustre-client [ 5202.276093] LustreError: 355288:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd46b16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5202.295767] LustreError: 355290:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5202.306699] LustreError: 355290:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5202.334956] Lustre: Unmounted lustre-client [ 5204.303888] Key type lgssc unregistered [ 5204.482688] LNet: 355532:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5204.489645] LNetError: 355532:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5204.500390] LNet: Removed LNI 192.168.204.54@tcp [ 5204.933658] Key type .llcrypt unregistered [ 5204.936734] Key type ._llcrypt unregistered [ 5205.343692] Key type ._llcrypt registered [ 5205.345384] Key type .llcrypt registered [ 5205.544873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5205.552386] alg: No test for adler32 (adler32-zlib) [ 5206.504128] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5206.650371] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5208.279495] Key type lgssc registered [ 5208.914756] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5216.161405] Lustre: DEBUG MARKER: Iteration 23 [ 5216.366840] LustreError: 356349:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5216.367699] LustreError: 356350:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5216.375867] LustreError: 356349:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 5217.546548] Lustre: Mounted lustre-client [ 5218.627237] LustreError: 356455:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50011800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5218.653468] LustreError: 356455:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5218.696862] Lustre: Unmounted lustre-client [ 5220.861359] Key type lgssc unregistered [ 5221.091533] LNet: 356699:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5221.095913] LNetError: 356699:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5221.114906] LNet: Removed LNI 192.168.204.54@tcp [ 5221.671172] Key type .llcrypt unregistered [ 5221.673562] Key type ._llcrypt unregistered [ 5222.186974] Key type ._llcrypt registered [ 5222.188625] Key type .llcrypt registered [ 5222.659330] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5222.669469] alg: No test for adler32 (adler32-zlib) [ 5223.748370] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5223.926358] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5225.599277] Key type lgssc registered [ 5226.596346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5235.018757] Lustre: DEBUG MARKER: Iteration 24 [ 5235.312454] LustreError: 357518:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5235.314272] LustreError: 357523:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5235.334930] LustreError: 357518:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 5237.559380] Lustre: Mounted lustre-client [ 5238.627356] LustreError: 357631:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5238.635616] LustreError: 357631:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5238.637772] LustreError: 357630:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5238.679204] Lustre: Unmounted lustre-client [ 5240.897591] Key type lgssc unregistered [ 5241.134887] LNet: 357871:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5241.140369] LNetError: 357871:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5241.159838] LNet: Removed LNI 192.168.204.54@tcp [ 5241.603513] Key type .llcrypt unregistered [ 5241.605254] Key type ._llcrypt unregistered [ 5242.076464] Key type ._llcrypt registered [ 5242.077993] Key type .llcrypt registered [ 5242.324260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5242.333620] alg: No test for adler32 (adler32-zlib) [ 5243.355315] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5243.517807] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5245.135241] Key type lgssc registered [ 5245.898924] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5253.812533] Lustre: DEBUG MARKER: Iteration 25 [ 5254.050591] LustreError: 358687:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5254.050901] LustreError: 358689:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5254.058553] LustreError: 358687:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 5255.216753] Lustre: Mounted lustre-client [ 5256.217560] LustreError: 358800:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd4a1f0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5256.231302] LustreError: 358801:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5256.233351] LustreError: 358800:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5256.258818] Lustre: Unmounted lustre-client [ 5258.710537] Key type lgssc unregistered [ 5258.917700] LNet: 359042:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5258.927445] LNetError: 359042:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5258.942075] LNet: Removed LNI 192.168.204.54@tcp [ 5259.423154] Key type .llcrypt unregistered [ 5259.427461] Key type ._llcrypt unregistered [ 5260.254763] Key type ._llcrypt registered [ 5260.256843] Key type .llcrypt registered [ 5260.415628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5260.430131] alg: No test for adler32 (adler32-zlib) [ 5261.416770] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5261.562401] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5263.231700] Key type lgssc registered [ 5264.155473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5272.623639] Lustre: DEBUG MARKER: Iteration 26 [ 5272.960038] LustreError: 359861:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5272.960453] LustreError: 359862:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5272.973702] LustreError: 359861:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 5274.204827] Lustre: Mounted lustre-client [ 5275.167881] LustreError: 359966:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd507c9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5275.195377] LustreError: 359966:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5275.229747] Lustre: Unmounted lustre-client [ 5277.974749] Key type lgssc unregistered [ 5278.254921] LNet: 360215:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5278.267668] LNetError: 360215:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5278.290397] LNet: Removed LNI 192.168.204.54@tcp [ 5278.860520] Key type .llcrypt unregistered [ 5278.862344] Key type ._llcrypt unregistered [ 5279.529939] Key type ._llcrypt registered [ 5279.534662] Key type .llcrypt registered [ 5279.823855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5279.843349] alg: No test for adler32 (adler32-zlib) [ 5280.869135] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5281.080153] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5282.759178] Key type lgssc registered [ 5284.052281] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5293.344502] Lustre: DEBUG MARKER: Iteration 27 [ 5293.671293] LustreError: 361034:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5293.674204] LustreError: 361033:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5293.680778] LustreError: 361034:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5294.931881] Lustre: Mounted lustre-client [ 5294.933598] Lustre: Skipped 1 previous similar message [ 5296.225680] LustreError: 361138:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd580cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5296.253354] LustreError: 361138:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5296.303398] Lustre: Unmounted lustre-client [ 5298.606519] Key type lgssc unregistered [ 5298.822308] LNet: 361388:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5298.834910] LNetError: 361388:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5298.856601] LNet: Removed LNI 192.168.204.54@tcp [ 5299.446859] Key type .llcrypt unregistered [ 5299.449809] Key type ._llcrypt unregistered [ 5300.128115] Key type ._llcrypt registered [ 5300.131977] Key type .llcrypt registered [ 5300.483099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5300.493976] alg: No test for adler32 (adler32-zlib) [ 5301.499788] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5301.647155] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5303.295171] Key type lgssc registered [ 5304.450219] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5313.305961] Lustre: DEBUG MARKER: Iteration 28 [ 5313.595345] LustreError: 362207:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5313.596106] LustreError: 362208:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5313.606980] LustreError: 362207:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5314.777961] Lustre: Mounted lustre-client [ 5315.662210] LustreError: 362313:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd58789800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5315.676094] LustreError: 362313:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5315.717806] Lustre: Unmounted lustre-client [ 5317.782958] Key type lgssc unregistered [ 5317.970875] LNet: 362557:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5317.976326] LNetError: 362557:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5317.986323] LNet: Removed LNI 192.168.204.54@tcp [ 5318.404688] Key type .llcrypt unregistered [ 5318.407790] Key type ._llcrypt unregistered [ 5318.941627] Key type ._llcrypt registered [ 5318.945109] Key type .llcrypt registered [ 5319.327755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5319.341185] alg: No test for adler32 (adler32-zlib) [ 5320.369466] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5320.542296] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5322.207374] Key type lgssc registered [ 5323.186445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5331.866283] Lustre: DEBUG MARKER: Iteration 29 [ 5332.158446] LustreError: 363374:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5332.161177] LustreError: 363375:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5332.171257] LustreError: 363374:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 5333.362110] Lustre: Mounted lustre-client [ 5334.493355] LustreError: 363482:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd70671800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5334.520672] LustreError: 363482:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5334.562646] Lustre: Unmounted lustre-client [ 5337.012027] Key type lgssc unregistered [ 5337.230627] LNet: 363729:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5337.237660] LNetError: 363729:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5338.276645] LNet: Removed LNI 192.168.204.54@tcp [ 5338.734211] Key type .llcrypt unregistered [ 5338.740282] Key type ._llcrypt unregistered [ 5339.261334] Key type ._llcrypt registered [ 5339.262915] Key type .llcrypt registered [ 5339.646608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5339.653974] alg: No test for adler32 (adler32-zlib) [ 5340.623416] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5340.828260] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5342.487713] Key type lgssc registered [ 5343.422718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5351.256535] Lustre: DEBUG MARKER: Iteration 30 [ 5351.469104] LustreError: 364548:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5351.471390] LustreError: 364547:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5351.485183] LustreError: 364548:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 5352.617669] Lustre: Mounted lustre-client [ 5353.305706] LustreError: 364655:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43f2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5353.320837] LustreError: 364655:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5353.357238] Lustre: Unmounted lustre-client [ 5354.824676] Key type lgssc unregistered [ 5355.000723] LNet: 364898:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5355.007995] LNetError: 364898:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5355.024670] LNet: Removed LNI 192.168.204.54@tcp [ 5355.445525] Key type .llcrypt unregistered [ 5355.447147] Key type ._llcrypt unregistered [ 5355.827311] Key type ._llcrypt registered [ 5355.834530] Key type .llcrypt registered [ 5356.074269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5356.085762] alg: No test for adler32 (adler32-zlib) [ 5357.061988] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5357.207945] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5358.831240] Key type lgssc registered [ 5359.594362] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5365.517130] Lustre: DEBUG MARKER: Iteration 31 [ 5365.710621] LustreError: 365716:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5365.710730] LustreError: 365717:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5365.718593] LustreError: 365716:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5366.830120] Lustre: Mounted lustre-client [ 5366.831789] Lustre: Skipped 1 previous similar message [ 5367.509257] LustreError: 365824:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd4986a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5367.515393] LustreError: 365824:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5367.522860] LustreError: 365824:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5367.544208] Lustre: Unmounted lustre-client [ 5368.792894] Key type lgssc unregistered [ 5368.929397] LNet: 366065:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5368.933279] LNetError: 366065:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5368.944844] LNet: Removed LNI 192.168.204.54@tcp [ 5369.248150] Key type .llcrypt unregistered [ 5369.249760] Key type ._llcrypt unregistered [ 5369.566477] Key type ._llcrypt registered [ 5369.568189] Key type .llcrypt registered [ 5369.765499] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5369.777526] alg: No test for adler32 (adler32-zlib) [ 5370.692388] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5370.824226] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5372.447187] Key type lgssc registered [ 5373.081070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5377.982948] Lustre: DEBUG MARKER: Iteration 32 [ 5378.166398] LustreError: 366895:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5378.166791] LustreError: 366896:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5378.173495] LustreError: 366895:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5379.291916] Lustre: Mounted lustre-client [ 5379.293561] Lustre: Skipped 1 previous similar message [ 5380.112184] LustreError: 367002:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd46214800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5380.122809] LustreError: 367002:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5380.142670] Lustre: Unmounted lustre-client [ 5381.775661] Key type lgssc unregistered [ 5381.998889] LNet: 367244:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5382.003428] LNetError: 367244:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5382.022136] LNet: Removed LNI 192.168.204.54@tcp [ 5382.406258] Key type .llcrypt unregistered [ 5382.407821] Key type ._llcrypt unregistered [ 5382.893770] Key type ._llcrypt registered [ 5382.895614] Key type .llcrypt registered [ 5383.107814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5383.119873] alg: No test for adler32 (adler32-zlib) [ 5384.106162] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5384.281840] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5385.911265] Key type lgssc registered [ 5386.613161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5393.130869] Lustre: DEBUG MARKER: Iteration 33 [ 5393.321895] LustreError: 368061:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5393.322540] LustreError: 368062:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5393.329933] LustreError: 368061:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5394.438118] Lustre: Mounted lustre-client [ 5394.446828] Lustre: Skipped 1 previous similar message [ 5395.160489] LustreError: 368171:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50545800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5395.167011] LustreError: 368171:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5395.168568] LustreError: 368174:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5395.202199] Lustre: Unmounted lustre-client [ 5396.492181] Key type lgssc unregistered [ 5396.659355] LNet: 368414:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5396.663556] LNetError: 368414:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5396.673506] LNet: Removed LNI 192.168.204.54@tcp [ 5397.074171] Key type .llcrypt unregistered [ 5397.076148] Key type ._llcrypt unregistered [ 5397.395173] Key type ._llcrypt registered [ 5397.399462] Key type .llcrypt registered [ 5397.626818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5397.636305] alg: No test for adler32 (adler32-zlib) [ 5398.543451] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5398.654737] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5400.263319] Key type lgssc registered [ 5400.972934] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5405.411076] Lustre: DEBUG MARKER: Iteration 34 [ 5405.533068] LustreError: 369231:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5405.534350] LustreError: 369232:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5405.538808] LustreError: 369231:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5406.595022] Lustre: Mounted lustre-client [ 5406.596987] Lustre: Skipped 1 previous similar message [ 5407.186809] LustreError: 369338:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43f2f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5407.192353] LustreError: 369338:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5407.193992] LustreError: 369339:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5407.216307] Lustre: Unmounted lustre-client [ 5408.490366] Key type lgssc unregistered [ 5408.632482] LNet: 369581:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5408.635218] LNetError: 369581:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5408.643694] LNet: Removed LNI 192.168.204.54@tcp [ 5408.944158] Key type .llcrypt unregistered [ 5408.945321] Key type ._llcrypt unregistered [ 5409.248401] Key type ._llcrypt registered [ 5409.250153] Key type .llcrypt registered [ 5409.472203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5409.479436] alg: No test for adler32 (adler32-zlib) [ 5410.365827] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5410.469446] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5412.063229] Key type lgssc registered [ 5412.588000] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5417.390580] Lustre: DEBUG MARKER: Iteration 35 [ 5417.535348] LustreError: 370398:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5417.535429] LustreError: 370399:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5417.541078] LustreError: 370398:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5418.609830] Lustre: Mounted lustre-client [ 5419.199503] LustreError: 370505:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50541000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5419.204768] LustreError: 370505:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5419.205316] LustreError: 370504:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5419.246431] Lustre: Unmounted lustre-client [ 5420.479257] Key type lgssc unregistered [ 5420.628726] LNet: 370746:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5420.634976] LNetError: 370746:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5420.649361] LNet: Removed LNI 192.168.204.54@tcp [ 5420.950202] Key type .llcrypt unregistered [ 5420.952164] Key type ._llcrypt unregistered [ 5421.312658] Key type ._llcrypt registered [ 5421.314281] Key type .llcrypt registered [ 5421.575713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5421.583179] alg: No test for adler32 (adler32-zlib) [ 5422.469830] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5422.579364] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5424.183181] Key type lgssc registered [ 5424.685180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5429.182789] Lustre: DEBUG MARKER: Iteration 36 [ 5429.281316] LustreError: 371564:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5429.282469] LustreError: 371566:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5429.286108] LustreError: 371564:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5430.358379] Lustre: Mounted lustre-client [ 5430.929972] LustreError: 371670:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd42e23800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5430.934548] LustreError: 371670:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5430.939132] LustreError: 371671:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5430.942251] LustreError: 371671:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5430.961093] Lustre: Unmounted lustre-client [ 5432.288181] Key type lgssc unregistered [ 5432.437430] LNet: 371911:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5432.441261] LNetError: 371911:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5432.450792] LNet: Removed LNI 192.168.204.54@tcp [ 5432.750172] Key type .llcrypt unregistered [ 5432.751784] Key type ._llcrypt unregistered [ 5433.017054] Key type ._llcrypt registered [ 5433.026901] Key type .llcrypt registered [ 5433.277383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5433.284750] alg: No test for adler32 (adler32-zlib) [ 5434.211398] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5434.325450] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5435.935269] Key type lgssc registered [ 5436.424644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5440.985672] Lustre: DEBUG MARKER: Iteration 37 [ 5441.114430] LustreError: 372728:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5441.114740] LustreError: 372729:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5441.120125] LustreError: 372728:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5442.210715] Lustre: Mounted lustre-client [ 5442.212275] Lustre: Skipped 1 previous similar message [ 5442.677776] LustreError: 372838:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43dbb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5442.680065] LustreError: 372839:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5442.681841] LustreError: 372838:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5442.702154] Lustre: Unmounted lustre-client [ 5443.806802] Key type lgssc unregistered [ 5443.929618] LNet: 373080:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5443.932611] LNetError: 373080:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5443.945784] LNet: Removed LNI 192.168.204.54@tcp [ 5444.234123] Key type .llcrypt unregistered [ 5444.235593] Key type ._llcrypt unregistered [ 5444.512500] Key type ._llcrypt registered [ 5444.514070] Key type .llcrypt registered [ 5444.704712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5444.710516] alg: No test for adler32 (adler32-zlib) [ 5445.591408] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5445.694068] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5447.287209] Key type lgssc registered [ 5447.843354] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5452.667309] Lustre: DEBUG MARKER: Iteration 38 [ 5452.808434] LustreError: 373895:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5452.811865] LustreError: 373902:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5452.815692] LustreError: 373895:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5453.900092] Lustre: Mounted lustre-client [ 5453.901544] Lustre: Skipped 1 previous similar message [ 5454.482568] LustreError: 374003:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43f2f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5454.488285] LustreError: 374003:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5454.492970] LustreError: 374004:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5454.528498] Lustre: Unmounted lustre-client [ 5455.703913] Key type lgssc unregistered [ 5455.844381] LNet: 374245:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5455.847390] LNetError: 374245:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5455.859707] LNet: Removed LNI 192.168.204.54@tcp [ 5456.133209] Key type .llcrypt unregistered [ 5456.136091] Key type ._llcrypt unregistered [ 5456.472240] Key type ._llcrypt registered [ 5456.473579] Key type .llcrypt registered [ 5456.719682] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5456.725376] alg: No test for adler32 (adler32-zlib) [ 5457.608234] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5457.705783] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5459.311158] Key type lgssc registered [ 5459.795493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5463.557452] Lustre: DEBUG MARKER: Iteration 39 [ 5463.694968] LustreError: 375062:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5463.695068] LustreError: 375063:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5463.700883] LustreError: 375062:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5464.761411] Lustre: Mounted lustre-client [ 5465.231253] LustreError: 375164:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5465.235230] LustreError: 375163:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5465.236219] LustreError: 375164:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5465.257950] Lustre: Unmounted lustre-client [ 5466.365977] Key type lgssc unregistered [ 5466.482592] LNet: 375406:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5466.485921] LNetError: 375406:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5466.492711] LNet: Removed LNI 192.168.204.54@tcp [ 5466.765120] Key type .llcrypt unregistered [ 5466.766325] Key type ._llcrypt unregistered [ 5467.031761] Key type ._llcrypt registered [ 5467.033283] Key type .llcrypt registered [ 5467.225588] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5467.231733] alg: No test for adler32 (adler32-zlib) [ 5468.103824] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5468.192941] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5469.783211] Key type lgssc registered [ 5470.198794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5473.913892] Lustre: DEBUG MARKER: Iteration 40 [ 5474.008793] LustreError: 376223:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5474.009907] LustreError: 376224:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5474.013509] LustreError: 376223:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5475.061605] Lustre: Mounted lustre-client [ 5475.063035] Lustre: Skipped 1 previous similar message [ 5475.497875] LustreError: 376324:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5475.509122] LustreError: 376324:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5475.511053] LustreError: 376324:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5475.521235] Lustre: Unmounted lustre-client [ 5476.515110] Key type lgssc unregistered [ 5476.633441] LNet: 376567:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5476.635714] LNetError: 376567:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5476.649300] LNet: Removed LNI 192.168.204.54@tcp [ 5476.908124] Key type .llcrypt unregistered [ 5476.909109] Key type ._llcrypt unregistered [ 5477.165596] Key type ._llcrypt registered [ 5477.166468] Key type .llcrypt registered [ 5477.320964] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5477.325717] alg: No test for adler32 (adler32-zlib) [ 5478.173536] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5478.247112] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5479.831140] Key type lgssc registered [ 5480.244707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5483.790105] Lustre: DEBUG MARKER: Iteration 41 [ 5483.909869] LustreError: 377385:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5483.909914] LustreError: 377386:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5483.914941] LustreError: 377385:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5484.966594] Lustre: Mounted lustre-client [ 5484.967723] Lustre: Skipped 1 previous similar message [ 5485.395609] LustreError: 377492:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd43dbb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5485.400080] LustreError: 377492:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5485.402760] LustreError: 377493:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5485.406299] LustreError: 377493:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5485.428339] Lustre: Unmounted lustre-client [ 5486.540843] Key type lgssc unregistered [ 5486.679470] LNet: 377735:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5486.682884] LNetError: 377735:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5486.694679] LNet: Removed LNI 192.168.204.54@tcp [ 5486.940154] Key type .llcrypt unregistered [ 5486.941369] Key type ._llcrypt unregistered [ 5487.177619] Key type ._llcrypt registered [ 5487.178953] Key type .llcrypt registered [ 5487.380180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5487.389761] alg: No test for adler32 (adler32-zlib) [ 5488.269524] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5488.360188] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5489.959223] Key type lgssc registered [ 5490.416463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5493.970083] Lustre: DEBUG MARKER: Iteration 42 [ 5494.067537] LustreError: 378564:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5494.068559] LustreError: 378563:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5494.072020] LustreError: 378564:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5495.123452] Lustre: Mounted lustre-client [ 5495.124952] Lustre: Skipped 1 previous similar message [ 5495.543600] LustreError: 378670:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd74284000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5495.547473] LustreError: 378669:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5495.548095] LustreError: 378670:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5495.565566] Lustre: Unmounted lustre-client [ 5496.591872] Key type lgssc unregistered [ 5496.709492] LNet: 378910:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5496.712709] LNetError: 378910:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5496.720592] LNet: Removed LNI 192.168.204.54@tcp [ 5496.982148] Key type .llcrypt unregistered [ 5496.983459] Key type ._llcrypt unregistered [ 5497.376628] Key type ._llcrypt registered [ 5497.377454] Key type .llcrypt registered [ 5497.496342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5497.501981] alg: No test for adler32 (adler32-zlib) [ 5498.345871] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5498.427616] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5500.007161] Key type lgssc registered [ 5500.393584] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5503.657810] Lustre: DEBUG MARKER: Iteration 43 [ 5503.792436] LustreError: 379727:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5503.792473] LustreError: 379728:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5503.798301] LustreError: 379727:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5504.856042] Lustre: Mounted lustre-client [ 5504.857944] Lustre: Skipped 1 previous similar message [ 5505.282910] LustreError: 379833:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd74283000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5505.285400] LustreError: 379832:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5505.287294] LustreError: 379833:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5505.300440] Lustre: Unmounted lustre-client [ 5506.328319] Key type lgssc unregistered [ 5506.454412] LNet: 380074:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5506.456880] LNetError: 380074:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5506.463694] LNet: Removed LNI 192.168.204.54@tcp [ 5506.697087] Key type .llcrypt unregistered [ 5506.698251] Key type ._llcrypt unregistered [ 5506.942716] Key type ._llcrypt registered [ 5506.943749] Key type .llcrypt registered [ 5507.131280] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5507.136761] alg: No test for adler32 (adler32-zlib) [ 5507.994696] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5508.080453] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5509.663210] Key type lgssc registered [ 5510.044723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5513.343137] Lustre: DEBUG MARKER: Iteration 44 [ 5513.451391] LustreError: 380892:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5513.451446] LustreError: 380893:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5513.457597] LustreError: 380892:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5514.505591] Lustre: Mounted lustre-client [ 5514.506640] Lustre: Skipped 1 previous similar message [ 5514.974252] LustreError: 380996:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd606ab000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5514.976971] LustreError: 380997:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5514.978163] LustreError: 380996:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5514.992085] Lustre: Unmounted lustre-client [ 5516.018846] Key type lgssc unregistered [ 5516.141423] LNet: 381238:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5516.143435] LNetError: 381238:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5516.150661] LNet: Removed LNI 192.168.204.54@tcp [ 5516.393242] Key type .llcrypt unregistered [ 5516.396338] Key type ._llcrypt unregistered [ 5516.642458] Key type ._llcrypt registered [ 5516.644083] Key type .llcrypt registered [ 5516.866100] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5516.872860] alg: No test for adler32 (adler32-zlib) [ 5517.748608] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5517.838416] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5519.423132] Key type lgssc registered [ 5519.852336] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5523.434572] Lustre: DEBUG MARKER: Iteration 45 [ 5523.548201] LustreError: 382057:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5523.549714] LustreError: 382058:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5523.553116] LustreError: 382057:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5524.604993] Lustre: Mounted lustre-client [ 5525.095080] LustreError: 382159:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50545800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5525.105691] LustreError: 382159:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5525.119079] Lustre: Unmounted lustre-client [ 5526.190853] Key type lgssc unregistered [ 5526.315532] LNet: 382402:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5526.318491] LNetError: 382402:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5526.325743] LNet: Removed LNI 192.168.204.54@tcp [ 5526.589154] Key type .llcrypt unregistered [ 5526.590849] Key type ._llcrypt unregistered [ 5526.932161] Key type ._llcrypt registered [ 5526.933869] Key type .llcrypt registered [ 5527.133842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5527.140310] alg: No test for adler32 (adler32-zlib) [ 5528.004930] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5528.086178] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5529.671225] Key type lgssc registered [ 5530.045481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5533.779464] Lustre: DEBUG MARKER: Iteration 46 [ 5533.901204] LustreError: 383219:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5533.901261] LustreError: 383220:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5533.907844] LustreError: 383219:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5534.972450] Lustre: Mounted lustre-client [ 5534.973628] Lustre: Skipped 1 previous similar message [ 5535.405641] LustreError: 383326:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd5fbe3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5535.409901] LustreError: 383325:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5535.410237] LustreError: 383326:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5535.424845] Lustre: Unmounted lustre-client [ 5536.549380] Key type lgssc unregistered [ 5536.736554] LNet: 383567:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5536.740818] LNetError: 383567:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5536.751328] LNet: Removed LNI 192.168.204.54@tcp [ 5537.012145] Key type .llcrypt unregistered [ 5537.013676] Key type ._llcrypt unregistered [ 5537.305184] Key type ._llcrypt registered [ 5537.306512] Key type .llcrypt registered [ 5537.478276] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5537.484139] alg: No test for adler32 (adler32-zlib) [ 5538.354031] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5538.460923] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5540.055172] Key type lgssc registered [ 5540.463685] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5543.784725] Lustre: DEBUG MARKER: Iteration 47 [ 5543.887134] LustreError: 384384:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5543.888259] LustreError: 384385:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5543.891397] LustreError: 384384:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5544.940845] Lustre: Mounted lustre-client [ 5544.941695] Lustre: Skipped 1 previous similar message [ 5545.358708] LustreError: 384492:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50010000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5545.363852] LustreError: 384492:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5545.366225] LustreError: 384492:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5545.376112] Lustre: Unmounted lustre-client [ 5546.318398] Key type lgssc unregistered [ 5546.459298] LNet: 384733:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5546.461421] LNetError: 384733:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5546.467532] LNet: Removed LNI 192.168.204.54@tcp [ 5546.705127] Key type .llcrypt unregistered [ 5546.706552] Key type ._llcrypt unregistered [ 5546.956345] Key type ._llcrypt registered [ 5546.957270] Key type .llcrypt registered [ 5547.130874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5547.135418] alg: No test for adler32 (adler32-zlib) [ 5547.981020] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5548.054453] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5549.631155] Key type lgssc registered [ 5550.027490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5553.556650] Lustre: DEBUG MARKER: Iteration 48 [ 5553.652856] LustreError: 385550:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5553.664942] LustreError: 385566:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5553.666775] LustreError: 385550:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 5554.715478] Lustre: Mounted lustre-client [ 5554.717369] Lustre: Skipped 1 previous similar message [ 5555.146882] LustreError: 385654:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50541800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5555.152995] LustreError: 385653:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5555.156017] LustreError: 385653:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5555.171098] Lustre: Unmounted lustre-client [ 5556.139116] Key type lgssc unregistered [ 5556.264448] LNet: 385895:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5556.268101] LNetError: 385895:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5556.276689] LNet: Removed LNI 192.168.204.54@tcp [ 5556.534121] Key type .llcrypt unregistered [ 5556.535182] Key type ._llcrypt unregistered [ 5556.759102] Key type ._llcrypt registered [ 5556.760188] Key type .llcrypt registered [ 5556.948715] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5556.954765] alg: No test for adler32 (adler32-zlib) [ 5557.807470] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5557.885319] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5559.463136] Key type lgssc registered [ 5559.890690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5563.596830] Lustre: DEBUG MARKER: Iteration 49 [ 5563.743087] LustreError: 386713:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5563.743151] LustreError: 386712:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5563.748036] LustreError: 386713:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5564.816147] Lustre: Mounted lustre-client [ 5565.305149] LustreError: 386814:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5565.309230] LustreError: 386814:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5565.313343] LustreError: 386813:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5565.315497] LustreError: 386813:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5565.333098] Lustre: Unmounted lustre-client [ 5566.419319] Key type lgssc unregistered [ 5566.539443] LNet: 387055:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5566.542101] LNetError: 387055:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5566.550773] LNet: Removed LNI 192.168.204.54@tcp [ 5566.805145] Key type .llcrypt unregistered [ 5566.806855] Key type ._llcrypt unregistered [ 5567.057868] Key type ._llcrypt registered [ 5567.058782] Key type .llcrypt registered [ 5567.250334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5567.255790] alg: No test for adler32 (adler32-zlib) [ 5568.127680] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5568.217624] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5569.807215] Key type lgssc registered [ 5570.277900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5573.719345] Lustre: DEBUG MARKER: Iteration 50 [ 5573.829452] LustreError: 387873:0:(llite_lib.c:1386:ll_fill_super()) cfs_race id 1417 sleeping [ 5573.829494] LustreError: 387874:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 waking [ 5573.834154] LustreError: 387873:0:(llite_lib.c:1386:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5574.879906] Lustre: Mounted lustre-client [ 5575.300742] LustreError: 387978:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50544000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5575.307489] LustreError: 387978:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5575.310313] LustreError: 387978:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5575.317079] Lustre: Unmounted lustre-client [ 5576.324960] Key type lgssc unregistered [ 5576.442428] LNet: 388220:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5576.444873] LNetError: 388220:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5576.453605] LNet: Removed LNI 192.168.204.54@tcp [ 5576.688173] Key type .llcrypt unregistered [ 5576.691303] Key type ._llcrypt unregistered [ 5576.919902] Key type ._llcrypt registered [ 5576.921227] Key type .llcrypt registered [ 5577.105766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5577.110903] alg: No test for adler32 (adler32-zlib) [ 5577.971291] Lustre: Lustre: Build Version: 2.16.57_28_g7ecc534 [ 5578.049787] LNet: Added LNI 192.168.204.54@tcp [8/256/0/180] [ 5579.631143] Key type lgssc registered [ 5580.011927] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5584.171404] Lustre: Mounted lustre-client [ 5586.362602] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 12:21:34 (1754410894) [ 5603.295128] Lustre: 389553:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1754410895/real 1754410895] req@ffff8edd7ab5b800 x1839633149207296/t0(0) o36->lustre-MDT0000-mdc-ffff8edd50501800@192.168.204.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1754410911 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5603.303437] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5603.313086] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection restored to (at 192.168.204.154@tcp) [ 5618.655160] Lustre: 389553:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1754410911/real 1754410911] req@ffff8edd7ab5b800 x1839633149207296/t0(0) o36->lustre-MDT0000-mdc-ffff8edd50501800@192.168.204.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1754410927 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5618.662383] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5618.671078] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection restored to (at 192.168.204.154@tcp) [ 5635.039126] Lustre: 389553:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1754410927/real 1754410927] req@ffff8edd7ab5b800 x1839633149207296/t0(0) o36->lustre-MDT0000-mdc-ffff8edd50501800@192.168.204.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1754410943 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5635.048743] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5635.059685] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection restored to (at 192.168.204.154@tcp) [ 5651.423197] Lustre: 389553:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1754410943/real 1754410943] req@ffff8edd7ab5b800 x1839633149207296/t0(0) o36->lustre-MDT0000-mdc-ffff8edd50501800@192.168.204.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1754410959 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5651.429779] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection to lustre-MDT0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5651.440596] Lustre: lustre-MDT0000-mdc-ffff8edd50501800: Connection restored to (at 192.168.204.154@tcp) [ 5651.980438] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 12:22:39 (1754410959) [ 5652.504149] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5653.081605] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 12:22:41 (1754410961) [ 5653.590426] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5654.152801] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 12:22:42 (1754410962) [ 5656.556419] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 12:22:44 (1754410964) [ 5657.090104] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5657.679170] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 12:22:45 (1754410965) [ 5658.201396] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5658.827502] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 12:22:46 (1754410966) [ 5659.360920] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5659.992105] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 12:22:47 (1754410967) [ 5678.047120] Lustre: 388412:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1754410970/real 1754410970] req@ffff8edd5f82ad80 x1839633149234816/t0(0) o4->lustre-OST0000-osc-ffff8edd50501800@192.168.204.154@tcp:6/4 lens 4584/448 e 0 to 1 dl 1754410986 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5678.047171] Lustre: lustre-OST0000-osc-ffff8edd50501800: Connection to lustre-OST0000 (at 192.168.204.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5678.054984] Lustre: 388412:0:(client.c:2453:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 5765.897895] Lustre: DEBUG MARKER: oleg454-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8edd494d5000.ost_server_uuid 50 [ 5766.400269] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8edd494d5000.ost_server_uuid in IDLE state after 0 sec [ 5766.945138] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5767.522517] Lustre: DEBUG MARKER: == sanityn test complete, duration 5621 sec ============== 12:24:35 (1754411075) [ 5768.114538] Lustre: DEBUG MARKER: === sanityn: start cleanup 12:24:36 (1754411076) === [ 5819.935125] LustreError: 393645:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd494d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5819.939411] LustreError: 393645:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5819.950071] Lustre: Unmounted lustre-client [ 5821.275236] Lustre: DEBUG MARKER: === sanityn: finish cleanup 12:25:29 (1754411129) === [ 5821.671950] LustreError: 393945:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff8edd50501800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5821.675195] LustreError: 393945:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 5821.680465] LustreError: 393945:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5821.682207] LustreError: 393945:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5821.699087] Lustre: Unmounted lustre-client [ 5829.823315] Key type lgssc unregistered [ 5829.955649] LNet: 394422:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5829.958913] LNetError: 394422:0:(acceptor.c:254:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5829.966725] LNet: Removed LNI 192.168.204.54@tcp [ 5830.241108] Key type .llcrypt unregistered [ 5830.242212] Key type ._llcrypt unregistered