[ 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 499118454 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 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.001012] APIC: Switch to symmetric I/O mode setup [ 0.003055] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008348] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009052] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010009] pid_max: default: 32768 minimum: 301 [ 0.011121] LSM: Security Framework initializing [ 0.012000] Yama: becoming mindful. [ 0.012031] SELinux: Initializing. [ 0.013053] *** VALIDATE selinux *** [ 0.021304] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025699] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027130] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029062] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030098] *** VALIDATE tmpfs *** [ 0.031417] *** VALIDATE proc *** [ 0.033161] *** VALIDATE cgroup *** [ 0.034000] *** VALIDATE cgroup2 *** [ 0.034328] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035143] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036000] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036023] Spectre V2 : User space: Vulnerable [ 0.037004] Speculative Store Bypass: Vulnerable [ 0.040011] debug: unmapping init [mem 0xffffffffa0e59000-0xffffffffa0e60fff] [ 0.042897] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043610] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044017] ... version: 2 [ 0.044947] ... bit width: 48 [ 0.045011] ... generic registers: 4 [ 0.045962] ... value mask: 0000ffffffffffff [ 0.046009] ... max period: 00007fffffffffff [ 0.047007] ... fixed-purpose events: 3 [ 0.047966] ... event mask: 000000070000000f [ 0.049006] rcu: Hierarchical SRCU implementation. [ 0.051272] smp: Bringing up secondary CPUs ... [ 0.052455] x86: Booting SMP configuration: [ 0.053013] .... node #0, CPUs: #1 #2 #3 [ 0.060063] smp: Brought up 1 node, 4 CPUs [ 0.062010] smpboot: Max logical packages: 1 [ 0.063012] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.208026] node 0 deferred pages initialised in 143ms [ 0.215253] devtmpfs: initialized [ 0.216394] x86/mm: Memory block size: 128MB [ 0.218974] gcov: version magic: 0x41383552 [ 0.220404] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.221092] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.222375] pinctrl core: initialized pinctrl subsystem [ 0.223274] [ 0.224020] ************************************************************* [ 0.225025] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.226023] ** ** [ 0.227012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.228017] ** ** [ 0.229014] ** This means that this kernel is built to expose internal ** [ 0.230011] ** IOMMU data structures, which may compromise security on ** [ 0.231011] ** your system. ** [ 0.232011] ** ** [ 0.233013] ** If you see this message and you are not debugging the ** [ 0.234012] ** kernel, report this immediately to your vendor! ** [ 0.235009] ** ** [ 0.236015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.237012] ************************************************************* [ 0.238665] NET: Registered protocol family 16 [ 0.239446] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.240060] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.241048] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.242433] cpuidle: using governor menu [ 0.243198] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.245573] PCI: Using configuration type 1 for base access [ 0.248120] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.263276] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.266159] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.269553] cryptd: max_cpu_qlen set to 1000 [ 0.272622] ACPI: Added _OSI(Module Device) [ 0.274021] ACPI: Added _OSI(Processor Device) [ 0.276011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.277014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.281000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.288655] ACPI: Interpreter enabled [ 0.290074] ACPI: PM: (supports S0 S3 S4 S5) [ 0.292010] ACPI: Using IOAPIC for interrupt routing [ 0.293765] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.297419] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.305705] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.308053] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.311021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.314135] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.320772] acpiphp: Slot [2] registered [ 0.322181] acpiphp: Slot [3] registered [ 0.323074] acpiphp: Slot [4] registered [ 0.325135] acpiphp: Slot [5] registered [ 0.326136] acpiphp: Slot [6] registered [ 0.328111] acpiphp: Slot [7] registered [ 0.329087] acpiphp: Slot [8] registered [ 0.330095] acpiphp: Slot [9] registered [ 0.332147] acpiphp: Slot [10] registered [ 0.334141] acpiphp: Slot [11] registered [ 0.335108] acpiphp: Slot [12] registered [ 0.338196] acpiphp: Slot [13] registered [ 0.340153] acpiphp: Slot [14] registered [ 0.341088] acpiphp: Slot [15] registered [ 0.343140] acpiphp: Slot [16] registered [ 0.344096] acpiphp: Slot [17] registered [ 0.346102] acpiphp: Slot [18] registered [ 0.347103] acpiphp: Slot [19] registered [ 0.350168] acpiphp: Slot [20] registered [ 0.352151] acpiphp: Slot [21] registered [ 0.354156] acpiphp: Slot [22] registered [ 0.356103] acpiphp: Slot [23] registered [ 0.357103] acpiphp: Slot [24] registered [ 0.359078] acpiphp: Slot [25] registered [ 0.360118] acpiphp: Slot [26] registered [ 0.361111] acpiphp: Slot [27] registered [ 0.363073] acpiphp: Slot [28] registered [ 0.364142] acpiphp: Slot [29] registered [ 0.366095] acpiphp: Slot [30] registered [ 0.368089] acpiphp: Slot [31] registered [ 0.369075] PCI host bridge to bus 0000:00 [ 0.371026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.373032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.376036] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378033] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.381080] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.383039] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.386239] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.390363] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.395354] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.402451] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.407058] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.409023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.412026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.414023] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.417571] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.420793] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.424055] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.427850] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.432016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.441024] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.446019] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.452374] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.458019] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.469023] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.486021] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.497405] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.504040] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.511016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.529013] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.545065] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.548508] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.550456] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.553472] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.555284] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.560093] iommu: Default domain type: Passthrough [ 0.562589] SCSI subsystem initialized [ 0.564216] ACPI: bus type USB registered [ 0.566155] usbcore: registered new interface driver usbfs [ 0.568083] usbcore: registered new interface driver hub [ 0.570142] usbcore: registered new device driver usb [ 0.573209] pps_core: LinuxPPS API ver. 1 registered [ 0.575009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.579071] PTP clock support registered [ 0.581141] EDAC MC: Ver: 3.0.0 [ 0.583119] PCI: Using ACPI for IRQ routing [ 0.584880] NetLabel: Initializing [ 0.585008] NetLabel: domain hash size = 128 [ 0.586006] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.587089] NetLabel: unlabeled traffic allowed by default [ 0.589116] vgaarb: loaded [ 0.591290] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.593014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.596589] clocksource: Switched to clocksource kvm-clock [ 0.725725] VFS: Disk quotas dquot_6.6.0 [ 0.727234] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.728990] *** VALIDATE ramfs *** [ 0.730180] *** VALIDATE hugetlbfs *** [ 0.731385] pnp: PnP ACPI init [ 0.733706] pnp: PnP ACPI: found 6 devices [ 0.749973] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.752406] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.753691] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.754894] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.756397] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.757805] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.759543] NET: Registered protocol family 2 [ 0.761834] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.766491] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.768972] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.773087] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.776080] TCP: Hash tables configured (established 65536 bind 65536) [ 0.778175] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.780370] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.782605] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.784562] NET: Registered protocol family 1 [ 0.788584] RPC: Registered named UNIX socket transport module. [ 0.789857] RPC: Registered udp transport module. [ 0.790831] RPC: Registered tcp transport module. [ 0.791880] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.793160] NET: Registered protocol family 44 [ 0.794137] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.795385] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.796620] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.798087] PCI: CLS 0 bytes, default 64 [ 0.799225] Unpacking initramfs... [ 2.325695] debug: unmapping init [mem 0xffff95d43cc64000-0xffff95d43ffcffff] [ 2.332225] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.334583] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.337833] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.920961] Initialise system trusted keyrings [ 2.922857] Key type blacklist registered [ 2.925722] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.945304] zbud: loaded [ 2.949237] *** VALIDATE nfs *** [ 2.950685] *** VALIDATE nfs4 *** [ 2.953494] pstore: using deflate compression [ 2.958044] Platform Keyring initialized [ 3.092947] NET: Registered protocol family 38 [ 3.095218] Key type asymmetric registered [ 3.097067] Asymmetric key parser 'x509' registered [ 3.098916] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.103651] io scheduler mq-deadline registered [ 3.105435] io scheduler kyber registered [ 3.107843] io scheduler bfq registered [ 3.109556] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.112964] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.117574] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.120368] ACPI: Power Button [PWRF] [ 3.256883] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.367565] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.474920] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.500974] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.528881] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.535270] Non-volatile memory driver v1.3 [ 3.536872] Linux agpgart interface v0.103 [ 3.565849] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.568891] vda: detected capacity change from 0 to 68526080 [ 3.583820] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.586864] vdb: detected capacity change from 0 to 1073741824 [ 3.592359] libphy: Fixed MDIO Bus: probed [ 3.600908] usbcore: registered new interface driver usbserial_generic [ 3.603147] usbserial: USB Serial support registered for generic [ 3.605766] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.611098] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.612690] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.615595] mousedev: PS/2 mouse device common for all mice [ 3.621354] rtc_cmos 00:05: RTC can wake from S4 [ 3.626343] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.631504] rtc_cmos 00:05: registered as rtc0 [ 3.634605] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.638064] intel_pstate: CPU model not supported [ 3.642092] hid: raw HID events driver (C) Jiri Kosina [ 3.642311] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.644940] usbcore: registered new interface driver usbhid [ 3.649894] usbhid: USB HID core driver [ 3.652081] drop_monitor: Initializing network drop monitor service [ 3.657942] Initializing XFRM netlink socket [ 3.661828] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.662997] NET: Registered protocol family 10 [ 3.689026] Segment Routing with IPv6 [ 3.690594] NET: Registered protocol family 17 [ 3.693661] mpls_gso: MPLS GSO support [ 3.702278] RAS: Correctable Errors collector initialized. [ 3.704192] AVX version of gcm_enc/dec engaged. [ 3.705746] AES CTR mode by8 optimization enabled [ 3.873487] sched_clock: Marking stable (3873409324, 0)->(4805592611, -932183287) [ 3.876919] registered taskstats version 1 [ 3.882552] Loading compiled-in X.509 certificates [ 3.884612] zswap: loaded using pool lzo/zbud [ 3.925582] Key type big_key registered [ 3.941361] Key type encrypted registered [ 3.942989] ima: No TPM chip found, activating TPM-bypass! [ 3.946762] ima: Allocated hash algorithm: sha1 [ 3.951261] ima: No architecture policies found [ 3.953275] evm: Initialising EVM extended attributes: [ 3.954927] evm: security.selinux [ 3.956107] evm: security.ima [ 3.957199] evm: security.capability [ 3.958505] evm: HMAC attrs: 0x1 [ 3.962257] rtc_cmos 00:05: setting system clock to 2025-11-16 22:24:53 UTC (1763331893) [ 3.975704] debug: unmapping init [mem 0xffffffffa1e03000-0xffffffffa1ffffff] [ 3.982611] debug: unmapping init [mem 0xffffffffa0b82000-0xffffffffa0e58fff] [ 4.003289] Write protecting the kernel read-only data: 28672k [ 4.010944] debug: unmapping init [mem 0xffffffff9f203000-0xffffffff9f3fffff] [ 4.016236] debug: unmapping init [mem 0xffffffff9fb14000-0xffffffff9fbfffff] [ 4.069599] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 4.078653] systemd[1]: Detected virtualization kvm. [ 4.082220] systemd[1]: Detected architecture x86-64. [ 4.085620] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.122991] systemd[1]: No hostname configured. [ 4.125453] systemd[1]: Set hostname to . [ 4.127914] random: systemd: uninitialized urandom read (16 bytes read) [ 4.130603] systemd[1]: Initializing machine ID from random generator. [ 4.212302] random: ln: uninitialized urandom read (6 bytes read) [ 4.400509] random: systemd: uninitialized urandom read (16 bytes read) [ 4.402948] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.414969] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 4.420343] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create Static Device Nodes in /dev. [ 4.919275] hrtimer: interrupt took 2629396 ns [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.686994] device-mapper: uevent: version 1.0.3 [ 5.690548] 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... [ 7.978989] virtio_net virtio0 ens2: renamed from eth0 [ 8.055550] random: fast init done [ 8.326477] scsi host0: ata_piix [ 8.436090] scsi host1: ata_piix [ 8.437882] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.457869] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.493144] random: crng init done [ 13.495291] random: 7 urandom warning(s) missed due to ratelimiting [ 16.723428] dracut-initqueue[593]: 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... [ 18.970463] 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 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 target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.632396] printk: systemd: 25 output lines suppressed due to ratelimiting [ 23.655332] SELinux: Disabled at runtime. [ 24.096814] 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) [ 24.112671] systemd[1]: Detected virtualization kvm. [ 24.118168] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 26.117933] systemd[1]: initrd-switch-root.service: Succeeded. [ 26.128019] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 26.150660] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 26.161892] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 26.168093] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 26.201076] systemd[1]: Starting Journal Service... Starting Journal Service... [ 26.215548] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached targ[ 27.033926] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS et Local Encrypted Volumes. [ OK ] Reached target Paths. Starting Remount Root and Kernel File Systems... [ OK ] Reached target rpc_pipefs.target. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... Mounting Kernel Debug File System... [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. 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 udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 27.978239] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 28.815076] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 28.856930] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.561429] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.655191] EDAC sbridge: Ver: 1.1.2 [ 33.548674] Key type dns_resolver registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 35.010254] NFS: Registering the id_resolver key type [ 35.012228] Key type id_resolver registered [ 35.022188] Key type id_legacy registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ 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 ] Reached target Basic System. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started irqbalance daemon. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ 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. Starting Hostname Service... [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting System Logging Service... Starting Notify NFS peers of a restart... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg458-client login: [ 107.021279] libcfs: loading out-of-tree module taints kernel. [ 107.052656] Key type ._llcrypt registered [ 107.054190] Key type .llcrypt registered [ 107.321739] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 107.346351] alg: No test for adler32 (adler32-zlib) [ 108.572308] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [ 108.997143] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 110.688291] Key type lgssc registered [ 112.028478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 282.990711] Lustre: Mounted lustre-client [ 288.546171] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 308.703789] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 23s idle [ 309.357975] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing check_logdir /tmp/testlogs/ [ 315.260777] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing yml_node [ 321.125342] Lustre: DEBUG MARKER: Client: 2.16.61.53 [ 323.876384] Lustre: DEBUG MARKER: MDS: 2.16.61.53 [ 327.035827] Lustre: DEBUG MARKER: OSS: 2.16.61.53 [ 329.088615] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 17:30:16 EST 2025 [ 348.440138] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 350.099228] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 351.666997] Lustre: DEBUG MARKER: === sanityn: start setup 17:30:39 (1763332239) === [ 352.699113] Lustre: Mounted lustre-client [ 356.489682] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing check_config_client /mnt/lustre [ 377.726260] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 392.829939] Lustre: DEBUG MARKER: === sanityn: finish setup 17:31:20 (1763332280) === [ 395.271861] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 17:31:23 (1763332283) [ 404.169590] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 17:31:32 (1763332292) [ 411.442953] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 17:31:39 (1763332299) [ 418.939283] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 17:31:46 (1763332306) [ 426.645160] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 17:31:54 (1763332314) [ 433.795720] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 17:32:01 (1763332321) [ 440.299633] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 17:32:08 (1763332328) [ 447.130299] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 17:32:15 (1763332335) [ 455.090632] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 17:32:22 (1763332342) [ 462.014843] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 17:32:30 (1763332350) [ 469.215224] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 17:32:37 (1763332357) [ 475.618454] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 20s idle [ 475.630299] Lustre: Skipped 1 previous similar message [ 477.243590] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 17:32:45 (1763332365) [ 483.948410] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 17:32:52 (1763332372) [ 490.975247] Lustre: lustre-OST0001-osc-ffff95d498522800: disconnect after 20s idle [ 491.134096] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 17:32:58 (1763332378) [ 499.133962] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 17:33:06 (1763332386) [ 506.230980] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 17:33:14 (1763332394) [ 511.460973] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 21s idle [ 513.535358] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 17:33:21 (1763332401) [ 519.629746] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 17:33:28 (1763332408) [ 527.906216] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 17:33:35 (1763332415) [ 534.754389] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 17:33:42 (1763332422) [ 541.772360] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 17:33:49 (1763332429) [ 542.584270] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 692.021143] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 17:36:19 (1763332579) [ 702.853672] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 17:36:30 (1763332590) [ 710.600043] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 17:36:38 (1763332598) [ 718.401743] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 17:36:46 (1763332606) [ 725.961555] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 17:36:53 (1763332613) [ 733.826723] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 17:37:01 (1763332621) [ 736.022471] Lustre: DEBUG MARKER: chmod [ 741.983430] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 17:37:10 (1763332630) [ 1526.248458] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 17:50:14 (1763333414) [ 1658.336510] Lustre: lustre-OST0001-osc-ffff95d498522800: disconnect after 20s idle [ 1737.563659] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 17:53:45 (1763333625) [ 1848.638749] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 17:55:37 (1763333737) [ 1984.291899] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 17:57:52 (1763333872) [ 2021.584988] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 17:58:30 (1763333910) [ 2026.983139] Lustre: lustre-OST0000-osc-ffff95d498522800: disconnect after 24s idle [ 2028.047156] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 17:58:36 (1763333916) [ 2029.102959] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.164487] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.208334] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.262672] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.342570] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.430085] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.481522] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.544141] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.617669] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.691790] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.762165] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.806945] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.889468] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2029.956713] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.029933] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.106820] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.192777] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.293520] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.381111] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.433782] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.532446] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.576825] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.654708] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.714484] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.795618] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.862057] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.935582] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2030.993373] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.087855] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.163435] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.231287] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.331533] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.416188] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.506569] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.562246] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.626299] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.700238] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.759765] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.836376] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.896119] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.938635] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2031.999353] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.069860] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.158227] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.248912] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.309627] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.371555] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.456681] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.543762] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.613490] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.709368] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.808231] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.904717] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2032.972585] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.017829] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.107431] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.193371] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.309423] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.368372] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.442432] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.523121] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.605922] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.726582] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.787322] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.826706] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.892826] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2033.991960] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.086777] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.141895] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.169801] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.236654] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.284837] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.377118] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.465211] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.546145] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.612361] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.677603] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.729877] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.801182] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.891529] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2034.950830] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.042371] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.105848] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.201990] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.286571] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.339250] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.410329] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.472996] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.553351] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.637979] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.706528] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.792377] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.867583] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2035.939710] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.025369] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.079283] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.129529] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.232655] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.316122] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.423798] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.520645] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.600054] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.664915] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.742244] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.815619] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2036.923656] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.062517] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.144344] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.205247] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.256477] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.358148] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.427149] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.536691] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.594755] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.660629] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.747715] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.873750] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2037.940199] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.001424] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.066257] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.112901] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.210900] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.284308] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.331561] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.409872] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.478186] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.544440] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.621164] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.709371] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.807404] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2038.922917] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.021820] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.109488] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.211980] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.331194] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.395707] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.480659] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.555153] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.637621] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.714269] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.791249] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.858701] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2039.971437] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.038874] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.104994] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.181512] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.231780] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.319801] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.384135] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.472793] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.524689] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.605116] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.662739] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.731456] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.776652] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.860077] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.922657] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2040.961778] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.035345] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.117935] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.206734] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.305512] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.358366] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.422094] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.519473] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.592063] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.680315] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.779513] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.858961] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.899449] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2041.946275] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.015944] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.095128] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.146436] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.214572] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.300174] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.335582] Lustre: lustre-OST0001-osc-ffff95d498522800: disconnect after 20s idle [ 2042.370052] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.409539] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.479765] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.560564] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.641263] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.757598] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.844647] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2042.939292] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.050417] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.143657] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.230964] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.296072] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.381954] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.473098] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.558177] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.694977] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.778350] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.866224] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2043.945499] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.064182] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.150269] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.258841] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.344499] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.441296] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.522599] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.617128] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.714811] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.788797] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.838661] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.897445] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2044.945572] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.014057] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.096304] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.167090] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.224586] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.296472] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.389935] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.472852] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.524601] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.578970] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.627338] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.720730] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.817328] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.871768] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.923593] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2045.976275] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.050069] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.131814] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.219881] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.317499] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.394554] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.468582] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.536484] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.618457] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.706928] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.803828] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.856041] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.892584] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.938346] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2046.974846] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.014105] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.082552] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.140561] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.226326] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.302318] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.390499] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.457371] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 21s idle [ 2047.493487] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.565436] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.662171] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.746084] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.824802] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.887182] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2047.977979] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2048.055577] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2048.124365] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2048.196760] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2048.281614] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2048.351758] rw_seq_cst_vs_d (32344): drop_caches: 3 [ 2055.103991] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 17:59:03 (1763333943) [ 2055.555047] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2055.647507] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2055.679128] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2055.759767] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2055.883311] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2055.979711] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.047176] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.235292] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.350452] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.407165] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.505841] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.536343] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.580304] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.685890] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.747449] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.793274] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2056.946516] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.170575] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.368691] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.470968] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.540089] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.652313] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.733553] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.781355] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2057.863886] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.065813] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.094734] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.219306] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.267060] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.362752] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.455293] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.602442] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.679135] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.754420] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.894172] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2058.967701] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.032041] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.061337] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.322934] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.496216] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.544024] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.694352] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.770683] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.867195] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2059.966823] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.094607] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.196692] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.306296] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.395039] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.452416] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.589437] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.690710] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.810886] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.851645] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2060.987452] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.028486] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.154521] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.289788] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.346660] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.388598] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.429244] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.511354] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.547457] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.638951] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.749668] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.827376] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2061.917222] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.028329] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.117162] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.236918] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.298328] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.397840] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.513094] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.583084] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.625826] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.664428] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.767858] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.883995] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2062.977870] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.040847] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.177932] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.447116] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.607808] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.642342] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.686716] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.798637] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.888956] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2063.918586] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.027138] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.262994] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.442545] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.554762] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.606140] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.756389] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.799025] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.830054] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2064.906920] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.003992] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.137795] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.167387] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.288834] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.377972] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.535838] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.644900] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.741818] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.830109] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.877636] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2065.934904] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.078789] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.138499] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.305627] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.334964] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.465562] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.618601] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.698519] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.775392] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2066.923750] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.012424] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.088561] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.114159] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.166722] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.231560] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.302748] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.435040] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.598270] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.632706] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.812405] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.860323] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.896147] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.935186] Lustre: lustre-OST0000-osc-ffff95d498522800: disconnect after 20s idle [ 2067.940644] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2067.974182] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.054729] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.093460] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.129602] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.244734] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.313527] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.501257] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.543629] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.613873] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.728648] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.792501] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.863342] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2068.891809] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.007398] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.040604] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.148770] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.252800] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.295701] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.495862] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.622721] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.749647] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.786794] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.812465] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.882844] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2069.919612] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.018596] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.128526] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.245957] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.420911] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.492354] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.628096] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.687717] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.817890] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2070.979367] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.078212] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.127145] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.297458] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.331827] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.458415] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.627485] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.673524] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.777638] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.824078] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2071.913154] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.000121] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.104860] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.265984] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.343501] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.441386] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.471384] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.583877] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.754811] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.836581] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2072.958316] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.020193] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.114795] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.267239] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.398297] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.556148] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.635402] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.671288] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.705556] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.789287] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.846149] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2073.918910] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.075123] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.131596] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.198755] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.285095] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.335583] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.417571] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.475167] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.663747] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.702849] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.803089] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.876550] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.908377] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2074.968458] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2075.001728] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2075.110117] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2075.148251] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2075.257316] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2075.280814] rw_seq_cst_vs_d (32929): drop_caches: 3 [ 2078.178848] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 24s idle [ 2081.542407] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 17:59:29 (1763333969) [ 2087.994100] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 17:59:36 (1763333976) [ 2094.006764] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 17:59:42 (1763333982) [ 2125.479208] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 18:00:13 (1763334013) [ 2125.843882] bash (35410): drop_caches: 3 [ 2129.039345] bash (35410): drop_caches: 3 [ 2132.234981] bash (35410): drop_caches: 3 [ 2135.399974] bash (35410): drop_caches: 3 [ 2138.536197] bash (35410): drop_caches: 3 [ 2141.683384] bash (35410): drop_caches: 3 [ 2144.786209] bash (35410): drop_caches: 3 [ 2147.987225] bash (35410): drop_caches: 3 [ 2151.133393] bash (35410): drop_caches: 3 [ 2154.495876] bash (35410): drop_caches: 3 [ 2157.697139] bash (35410): drop_caches: 3 [ 2160.843965] bash (35410): drop_caches: 3 [ 2164.138216] bash (35410): drop_caches: 3 [ 2167.390335] bash (35410): drop_caches: 3 [ 2173.390358] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 18:01:00 (1763334060) [ 2185.679164] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 18:01:13 (1763334073) [ 2232.054496] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 18:01:59 (1763334119) [ 2240.354819] Lustre: DEBUG MARKER: loop 5 [ 2245.118487] Lustre: DEBUG MARKER: loop 10 [ 2247.137090] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 24s idle [ 2249.441303] Lustre: DEBUG MARKER: loop 15 [ 2253.782975] Lustre: DEBUG MARKER: loop 20 [ 2261.430351] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 18:02:29 (1763334149) [ 2267.898847] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 18:02:36 (1763334156) [ 2274.351138] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 18:02:42 (1763334162) [ 2282.975875] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 21s idle [ 2282.988450] Lustre: Skipped 1 previous similar message [ 2342.222471] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 18:03:50 (1763334230) [ 2348.192203] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 18:03:56 (1763334236) [ 2353.646783] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 18:04:01 (1763334241) [ 2360.434806] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 18:04:08 (1763334248) [ 2366.538242] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 18:04:15 (1763334255) [ 2373.706204] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 18:04:21 (1763334261) [ 2380.255478] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 20s idle [ 2380.266305] Lustre: Skipped 2 previous similar messages [ 2381.979063] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 18:04:29 (1763334269) [ 2388.517455] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2390.049958] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 18:04:38 (1763334278) [ 2398.258734] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 18:04:46 (1763334286) [ 2398.684783] Lustre: *** cfs_fail_loc=314, val=0*** [ 2399.711743] Lustre: *** cfs_fail_loc=314, val=0*** [ 2399.716615] Lustre: Skipped 2 previous similar messages [ 2403.891670] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 18:04:52 (1763334292) [ 2410.282299] Lustre: *** cfs_fail_loc=314, val=0*** [ 2414.014383] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 18:05:02 (1763334302) [ 2414.161259] LustreError: 46933:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2415.209804] LustreError: lustre-OST0000-osc-ffff95d498522800: operation ldlm_enqueue to node 192.168.204.158@tcp failed: rc = -107 [ 2415.214388] Lustre: lustre-OST0000-osc-ffff95d498522800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2415.225123] LustreError: lustre-OST0000-osc-ffff95d498522800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2415.234889] Lustre: lustre-OST0000-osc-ffff95d498522800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2417.183425] LustreError: 46933:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2420.881112] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 18:05:09 (1763334309) [ 2426.616696] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 18:05:15 (1763334315) [ 2432.953970] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2434.719078] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 18:05:22 (1763334322) [ 2436.049964] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2437.923808] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 18:05:26 (1763334326) [ 2441.713133] Lustre: lustre-MDT0000-mdc-ffff95d4a0375800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2451.960124] LustreError: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 2451.979604] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x8c7f9e67cee4a292 to 0x8c7f9e67ceef644e [ 2451.992229] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2452.005968] LustreError: 2414:0:(client.c:3418:ptlrpc_replay_interpret()) @@@ status 301, old was 0 req@ffff95d48832df80 x1848987627099264/t4294978907(4294978907) o101->lustre-MDT0000-mdc-ffff95d4a0375800@192.168.204.158@tcp:12/10 lens 576/608 e 0 to 0 dl 1763334357 ref 2 fl Interpret:RPQU/604/0 rc 301/301 job:'cat.0' uid:0 gid:0 projid:0 [ 2454.310103] Lustre: lustre-MDT0000-mdc-ffff95d4a0375800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2481.627031] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 18:06:10 (1763334370) [ 2524.833806] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 18:06:53 (1763334413) [ 2545.552520] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 18:07:13 (1763334433) [ 2599.371902] Lustre: lustre-OST0000-osc-ffff95d498522800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2599.381913] Lustre: Skipped 1 previous similar message [ 2599.397088] LustreError: lustre-OST0000-osc-ffff95d498522800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2599.404240] LustreError: lustre-OST0000-osc-ffff95d4a0375800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2599.408774] Lustre: lustre-OST0000-osc-ffff95d498522800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2599.413135] Lustre: Skipped 1 previous similar message [ 2614.731770] Lustre: lustre-OST0001-osc-ffff95d4a0375800: Connection to lustre-OST0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2614.755812] Lustre: Skipped 1 previous similar message [ 2614.775596] LustreError: lustre-OST0001-osc-ffff95d4a0375800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2614.786654] Lustre: lustre-OST0001-osc-ffff95d4a0375800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2614.795600] Lustre: Skipped 1 previous similar message [ 2620.895353] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 22s idle [ 2620.898406] Lustre: Skipped 3 previous similar messages [ 2629.189496] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid 50 [ 2630.515485] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid in IDLE state after 0 sec [ 2633.371400] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid 50 [ 2634.552818] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid in FULL state after 0 sec [ 2638.468586] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid 50 [ 2639.825135] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid in IDLE state after 0 sec [ 2642.632835] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid 50 [ 2643.858354] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid in FULL state after 0 sec [ 2652.421974] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid 50 [ 2653.821579] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95d498522800.ost_server_uuid in IDLE state after 0 sec [ 2657.232593] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid 50 [ 2658.949771] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff95d498522800.ost_server_uuid in FULL state after 0 sec [ 2660.587096] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 18:09:08 (1763334548) [ 2663.261476] Lustre: DEBUG MARKER: Race attempt 0 [ 2666.038131] Lustre: DEBUG MARKER: Wait for 57411 57438 for 60 sec... [ 2732.227783] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 18:10:20 (1763334620) [ 2739.885201] Lustre: DEBUG MARKER: start test - cycle (0) [ 2759.457332] Lustre: DEBUG MARKER: start test - cycle (1) [ 2779.758139] Lustre: DEBUG MARKER: start test - cycle (2) [ 2801.411817] Lustre: DEBUG MARKER: start test - cycle (3) [ 2825.787469] Lustre: DEBUG MARKER: start test - cycle (4) [ 2846.886565] Lustre: DEBUG MARKER: start test - cycle (5) [ 2867.208459] Lustre: DEBUG MARKER: start test - cycle (6) [ 2887.680964] Lustre: DEBUG MARKER: start test - cycle (7) [ 2908.538554] Lustre: DEBUG MARKER: start test - cycle (8) [ 2928.096944] Lustre: DEBUG MARKER: start test - cycle (9) [ 2949.426789] Lustre: DEBUG MARKER: start test - cycle (10) [ 2975.631892] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 18:14:23 (1763334863) [ 2984.416218] Lustre: lustre-OST0000-osc-ffff95d498522800: disconnect after 23s idle [ 2984.424449] Lustre: Skipped 3 previous similar messages [ 3055.000307] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 18:15:43 (1763334943) [ 3062.692909] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 18:15:50 (1763334950) [ 3069.881135] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 18:15:58 (1763334958) [ 3078.131547] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 18:16:06 (1763334966) [ 3078.631799] Lustre: *** cfs_fail_loc=411, val=0*** [ 3084.917956] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3086.338142] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 18:16:14 (1763334974) [ 3102.212830] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 18:16:30 (1763334990) [ 3118.676359] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 18:16:47 (1763335007) [ 3134.203077] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 18:17:02 (1763335022) [ 3150.581504] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 18:17:18 (1763335038) [ 3163.389703] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 18:17:31 (1763335051) [ 3176.987142] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 18:17:45 (1763335065) [ 3190.405984] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 18:17:58 (1763335078) [ 3203.196566] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 18:18:11 (1763335091) [ 3216.507785] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 18:18:24 (1763335104) [ 3229.978851] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 18:18:37 (1763335117) [ 3243.972453] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 18:18:52 (1763335132) [ 3259.890745] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 18:19:07 (1763335147) [ 3875.295251] Lustre: lustre-OST0000-osc-ffff95d498522800: disconnect after 21s idle [ 3875.298025] Lustre: Skipped 11 previous similar messages [ 4287.525365] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:36:15 (1763336175) [ 4301.930452] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:36:29 (1763336189) [ 4314.984413] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:36:43 (1763336203) [ 4328.487172] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:36:56 (1763336216) [ 4343.971890] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:37:11 (1763336231) [ 4359.025605] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:37:27 (1763336247) [ 4371.869165] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:37:40 (1763336260) [ 4384.791799] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:37:53 (1763336273) [ 4398.945981] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:38:07 (1763336287) [ 4511.580102] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:39:59 (1763336399) [ 4525.111828] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:40:13 (1763336413) [ 4537.909828] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:40:26 (1763336426) [ 4551.951427] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:40:40 (1763336440) [ 4564.650487] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 18:40:52 (1763336452) [ 4578.570567] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 18:41:06 (1763336466) [ 4591.163251] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 18:41:19 (1763336479) [ 4603.983662] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 18:41:32 (1763336492) [ 4615.235559] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 18:41:43 (1763336503) [ 4622.816307] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 23s idle [ 4622.827516] Lustre: Skipped 4 previous similar messages [ 4722.525281] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 18:43:30 (1763336610) [ 5226.979024] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 23s idle [ 5226.991448] Lustre: Skipped 6 previous similar messages [ 5845.350118] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 19:02:13 (1763337733) [ 5851.619750] Lustre: lustre-OST0001-osc-ffff95d498522800: disconnect after 24s idle [ 5856.843753] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 19:02:24 (1763337744) [ 5868.582268] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 19:02:36 (1763337756) [ 5881.011400] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 19:02:49 (1763337769) [ 5893.238813] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 19:03:01 (1763337781) [ 5906.018422] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 19:03:14 (1763337794) [ 5919.500530] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 19:03:27 (1763337807) [ 5932.226839] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 19:03:40 (1763337820) [ 5947.086653] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 19:03:55 (1763337835) [ 5959.984080] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 19:04:08 (1763337848) [ 6135.010579] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:07:03 (1763338023) [ 6147.487835] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:07:15 (1763338035) [ 6159.850732] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:07:27 (1763338047) [ 6172.445338] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:07:40 (1763338060) [ 6185.807852] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:07:53 (1763338073) [ 6198.185268] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 19:08:06 (1763338086) [ 6211.208913] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 19:08:19 (1763338099) [ 6223.149158] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 19:08:31 (1763338111) [ 6234.805861] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 19:08:43 (1763338123) [ 7402.039078] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 19:28:10 (1763339290) [ 7414.799414] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 19:28:23 (1763339303) [ 7418.346080] Lustre: lustre-OST0001-osc-ffff95d498522800: disconnect after 24s idle [ 7418.356060] Lustre: Skipped 7 previous similar messages [ 7427.178102] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 19:28:35 (1763339315) [ 7439.124975] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 19:28:47 (1763339327) [ 7450.874403] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 19:28:59 (1763339339) [ 7462.729050] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 19:29:11 (1763339351) [ 7474.040533] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 19:29:22 (1763339362) [ 7485.530253] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 19:29:33 (1763339373) [ 7497.378768] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 19:29:45 (1763339385) [ 7508.278579] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 19:29:56 (1763339396) [ 7515.617250] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 22s idle [ 7515.620255] Lustre: Skipped 2 previous similar messages [ 7519.937329] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 19:30:08 (1763339408) [ 7534.100227] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 19:30:21 (1763339421) [ 7547.165450] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 19:30:35 (1763339435) [ 7558.027428] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 19:30:46 (1763339446) [ 7569.374206] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 19:30:57 (1763339457) [ 7578.884125] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 19:31:07 (1763339467) [ 7591.793928] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 19:31:19 (1763339479) [ 7592.118636] LustreError: 22700:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7594.227749] LustreError: 22700:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7603.546692] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 19:31:31 (1763339491) [ 7612.952221] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 19:31:41 (1763339501) [ 7613.521607] LustreError: 237796:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7617.593895] LustreError: 237796:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7617.618824] LustreError: 237796:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7621.679962] LustreError: 237796:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7621.740642] LustreError: 237803:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7625.799098] LustreError: 237803:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7631.489424] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 19:32:00 (1763339520) [ 7642.377778] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 19:32:10 (1763339530) [ 7649.503595] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 19:32:18 (1763339538) [ 7658.180347] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 19:32:26 (1763339546) [ 7689.327758] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 19:32:57 (1763339577) [ 7701.692319] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 19:33:09 (1763339589) [ 7713.860630] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 19:33:22 (1763339602) [ 7733.828847] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 19:33:41 (1763339621) [ 7747.845874] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 19:33:56 (1763339636) [ 7764.404205] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 19:34:12 (1763339652) [ 7782.662116] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 19:34:30 (1763339670) [ 7804.730463] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 19:34:52 (1763339692) [ 7821.811713] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 19:35:09 (1763339709) [ 7902.555599] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 19:36:31 (1763339791) [ 7911.105862] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7917.166108] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 19:36:45 (1763339805) [ 7923.453382] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 19:36:52 (1763339812) [ 7928.988227] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 19:36:57 (1763339817) [ 7934.755779] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 19:37:02 (1763339822) [ 7977.957202] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 19:37:46 (1763339866) [ 7996.895214] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 23s idle [ 7996.898362] Lustre: Skipped 4 previous similar messages [ 8021.385091] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 19:38:29 (1763339909) [ 8027.573942] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 19:38:35 (1763339915) [ 8034.891663] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 19:38:43 (1763339923) [ 8038.326590] LustreError: lustre-MDT0000-mdc-ffff95d498522800: operation ldlm_enqueue to node 192.168.204.158@tcp failed: rc = -35 [ 8044.897610] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 19:38:53 (1763339933) [ 8045.521384] LustreError: 2415:0:(osc_request.c:3096:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8047.607181] LustreError: 2415:0:(osc_request.c:3096:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8055.960391] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 19:39:04 (1763339944) [ 8193.367678] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 19:41:21 (1763340081) [ 8204.220370] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 19:41:32 (1763340092) [ 8219.782543] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 19:41:47 (1763340107) [ 8233.623121] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 19:42:01 (1763340121) [ 8247.541629] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 19:42:15 (1763340135) [ 8266.003484] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 19:42:34 (1763340154) [ 8285.881326] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 19:42:54 (1763340174) [ 8295.508664] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 19:43:03 (1763340183) [ 8304.097985] Lustre: lustre-OST0000-osc-ffff95d4a0375800: disconnect after 24s idle [ 8304.100495] Lustre: Skipped 4 previous similar messages [ 8306.174638] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 19:43:14 (1763340194) [ 8323.419614] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 19:43:31 (1763340211) [ 8375.564784] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 19:44:23 (1763340263) [ 8504.675038] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 19:46:32 (1763340392) [ 8639.039716] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 19:48:46 (1763340526) [ 8923.616665] Lustre: lustre-OST0001-osc-ffff95d4a0375800: disconnect after 23s idle [ 8923.621358] Lustre: Skipped 15 previous similar messages [ 8973.035552] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 19:54:20 (1763340860) [ 9012.744654] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 19:55:00 (1763340900) [ 9053.310035] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 19:55:41 (1763340941) [ 9089.625351] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 19:56:17 (1763340977) [ 9164.873243] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 19:57:33 (1763341053) [ 9233.788790] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 19:58:41 (1763341121) [ 9344.328092] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 20:00:32 (1763341232) [ 9348.414789] LustreError: 283167:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0375800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9348.421830] LustreError: 283167:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9348.479669] Lustre: Unmounted lustre-client [ 9350.521481] LustreError: 283180:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d498522800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9350.527381] LustreError: 283180:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9350.536550] LustreError: 283180:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9350.540700] LustreError: 283180:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9350.581637] Lustre: Unmounted lustre-client [ 9461.175801] Lustre: Mounted lustre-client [ 9464.051472] Lustre: Mounted lustre-client [ 9467.277682] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9556.449300] Lustre: lustre-OST0000-osc-ffff95d488af0800: disconnect after 24s idle [ 9556.451562] Lustre: Skipped 13 previous similar messages [ 9560.172959] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 20:04:08 (1763341448) [ 9578.378346] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 20:04:26 (1763341466) [ 9590.859205] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 20:04:38 (1763341478) [ 9648.730780] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 20:05:36 (1763341536) [ 9719.295474] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 20:06:47 (1763341607) [ 9730.156607] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 20:06:58 (1763341618) [ 9851.129633] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 20:08:58 (1763341738) [ 9890.044235] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 20:09:38 (1763341778) [ 9943.890652] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 20:10:32 (1763341832) [ 9952.568708] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 20:10:40 (1763341840) [ 9963.338942] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 20:10:51 (1763341851) [ 9973.088937] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 20:11:01 (1763341861) [ 9975.078536] LustreError: 300150:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x16d:0x0]: rc = -5 [ 9975.083620] LustreError: 300150:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9975.585224] LustreError: 299978:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x16b:0x0]: rc = -5 [ 9975.601547] LustreError: 299978:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 2 previous similar messages [ 9975.617380] LustreError: 299978:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9975.634359] LustreError: 299978:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 2 previous similar messages [ 9976.626639] LustreError: 300181:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2b:0x0]: rc = -5 [ 9976.631213] LustreError: 300181:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 2 previous similar messages [ 9976.635078] LustreError: 300181:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9976.638070] LustreError: 300181:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 2 previous similar messages [ 9978.985805] LustreError: 300221:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x179:0x0]: rc = -5 [ 9978.994565] LustreError: 300221:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 7 previous similar messages [ 9979.015201] LustreError: 300221:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9979.030093] LustreError: 300221:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 7 previous similar messages [ 9980.081808] LustreError: 300239:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff95d489f8e000: inode [0x2000013a1:0x182:0x0] mdc close failed: rc = -2 [ 9983.300369] LustreError: 300292:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x191:0x0]: rc = -5 [ 9983.310712] LustreError: 300292:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 13 previous similar messages [ 9983.319701] LustreError: 300292:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9983.329968] LustreError: 300292:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 13 previous similar messages [ 9991.674851] LustreError: 300452:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x70:0x0]: rc = -5 [ 9991.689624] LustreError: 300452:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 46 previous similar messages [ 9991.699095] LustreError: 300452:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9991.708574] LustreError: 300452:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 46 previous similar messages [10007.870871] LustreError: 300761:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xc5:0x0]: rc = -5 [10007.882447] LustreError: 300761:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 89 previous similar messages [10007.893765] LustreError: 300761:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10007.905062] LustreError: 300761:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 89 previous similar messages [10021.066327] LustreError: 301024:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff95d489f8e000: inode [0x2000013a1:0x271:0x0] mdc close failed: rc = -2 [10041.280902] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 20:12:09 (1763341929) [10048.680789] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 20:12:16 (1763341936) [10253.493670] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 20:15:41 (1763342141) [10254.851822] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10256.308398] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 20:15:44 (1763342144) [10469.797980] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 20:19:17 (1763342357) [10476.328623] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 20:19:24 (1763342364) [10493.407362] Lustre: lustre-OST0000-osc-ffff95d489f8e000: disconnect after 24s idle [10493.411642] Lustre: Skipped 7 previous similar messages [10601.686582] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 20:21:29 (1763342489) [10613.419695] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 20:21:41 (1763342501) [10622.296903] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 20:21:50 (1763342510) [10808.774961] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 20:24:56 (1763342696) [10996.076563] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 20:28:03 (1763342883) [11003.369604] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 20:28:11 (1763342891) [11020.066178] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 20:28:28 (1763342908) [11020.402530] Lustre: DEBUG MARKER: write [11020.482088] LustreError: 284911:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [11022.503819] Lustre: DEBUG MARKER: kill 332164 [11022.514439] LustreError: 332164:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [11025.507238] LustreError: 284911:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [11028.615322] LustreError: 332164:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [11035.282093] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 20:28:43 (1763342923) [11037.880583] LustreError: 332779:0:(rw.c:1969:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [11047.887191] LustreError: 332779:0:(rw.c:1969:ll_readpage()) cfs_fail_timeout id 1422 awake [11053.604736] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:29:01 (1763342941) [11053.975328] LustreError: 333366:0:(rw.c:2214:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [11056.071320] LustreError: 333366:0:(rw.c:2214:ll_readpage()) cfs_fail_timeout interrupted [11065.381829] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:29:13 (1763342953) [11066.691813] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [11068.418409] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:29:16 (1763342956) [11074.146090] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:29:22 (1763342962) [11079.715856] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:29:28 (1763342968) [11085.450650] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:29:33 (1763342973) [11091.811882] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:29:40 (1763342980) [11097.472040] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:29:45 (1763342985) [11103.245543] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:29:51 (1763342991) [11110.045560] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:29:58 (1763342998) [11118.351585] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:30:06 (1763343006) [11119.877574] Lustre: *** cfs_fail_loc=415, val=0*** [11131.261108] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:30:19 (1763343019) [11160.397345] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:30:48 (1763343048) [11160.846327] LustreError: 285603:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [11160.857045] LustreError: 285603:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [11165.863146] LustreError: 285603:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11165.867232] LustreError: 285603:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [11175.937072] LustreError: 285605:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11175.939574] LustreError: 285605:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [11187.483055] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:31:15 (1763343075) [11195.056857] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:31:22 (1763343082) [11202.356544] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:31:30 (1763343090) [11208.997850] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:31:37 (1763343097) [11218.130326] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:31:46 (1763343106) [11230.403346] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:31:58 (1763343118) [11231.009737] LustreError: 344114:0:(osc_request.c:2947:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11231.013321] LustreError: 344114:0:(osc_request.c:2947:osc_build_rpc()) Skipped 6 previous similar messages [11235.079152] LustreError: 344114:0:(osc_request.c:2947:osc_build_rpc()) cfs_fail_timeout id 414 awake [11235.084395] LustreError: 344114:0:(osc_request.c:2947:osc_build_rpc()) Skipped 3 previous similar messages [11241.607488] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:32:09 (1763343129) [11245.064835] LustreError: 344824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d488af0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11245.087380] LustreError: 344824:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11245.101165] LustreError: 344824:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11245.112439] LustreError: 344824:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11245.185572] Lustre: Unmounted lustre-client [11246.801262] LustreError: 344844:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d489f8e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11246.824704] LustreError: 344844:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11246.844294] LustreError: 344844:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11246.857194] LustreError: 344844:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11246.961490] Lustre: Unmounted lustre-client [11248.187820] Lustre: DEBUG MARKER: Iteration 1 [11248.524309] LustreError: 345008:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11248.528788] LustreError: 345009:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11248.533867] LustreError: 345008:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11248.757770] Lustre: Mounted lustre-client [11249.831566] LustreError: 345123:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d485395800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11249.843273] LustreError: 345123:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11249.856564] LustreError: 345123:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11249.860894] LustreError: 345123:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11249.923768] Lustre: Unmounted lustre-client [11252.160663] Key type lgssc unregistered [11252.373351] LNet: 345367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11252.381374] LNetError: 345367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11252.398411] LNet: Removed LNI 192.168.204.58@tcp [11253.110178] Key type .llcrypt unregistered [11253.113958] Key type ._llcrypt unregistered [11253.808711] Key type ._llcrypt registered [11253.810549] Key type .llcrypt registered [11254.201285] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11254.217760] alg: No test for adler32 (adler32-zlib) [11255.496689] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11256.138676] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11257.839262] Key type lgssc registered [11259.405929] Lustre: Echo OBD driver; http://www.lustre.org/ [11270.107979] Lustre: DEBUG MARKER: Iteration 2 [11270.405453] LustreError: 346198:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11270.406508] LustreError: 346199:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11270.424433] LustreError: 346198:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11271.721872] Lustre: Mounted lustre-client [11272.726514] LustreError: 346313:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d489f8c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11272.741971] LustreError: 346313:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11272.798610] Lustre: Unmounted lustre-client [11274.985323] Key type lgssc unregistered [11275.241090] LNet: 346555:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11275.247516] LNetError: 346555:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11275.272591] LNet: Removed LNI 192.168.204.58@tcp [11275.905357] Key type .llcrypt unregistered [11275.907981] Key type ._llcrypt unregistered [11276.550198] Key type ._llcrypt registered [11276.551891] Key type .llcrypt registered [11277.066223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11277.076890] alg: No test for adler32 (adler32-zlib) [11278.118648] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11278.295154] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11279.983229] Key type lgssc registered [11281.190596] Lustre: Echo OBD driver; http://www.lustre.org/ [11291.171199] Lustre: DEBUG MARKER: Iteration 3 [11291.557160] LustreError: 347383:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11291.557645] LustreError: 347385:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11291.570284] LustreError: 347383:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11292.790461] Lustre: Mounted lustre-client [11293.680449] LustreError: 347502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d486c63800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11293.686737] LustreError: 347502:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11293.691816] LustreError: 347501:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11293.743235] Lustre: Unmounted lustre-client [11296.065721] Key type lgssc unregistered [11296.330526] LNet: 347744:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11296.346363] LNetError: 347744:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11296.361232] LNet: Removed LNI 192.168.204.58@tcp [11297.113557] Key type .llcrypt unregistered [11297.115715] Key type ._llcrypt unregistered [11297.791300] Key type ._llcrypt registered [11297.792725] Key type .llcrypt registered [11298.152260] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11298.162796] alg: No test for adler32 (adler32-zlib) [11299.185337] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11299.388384] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11301.087249] Key type lgssc registered [11302.232797] Lustre: Echo OBD driver; http://www.lustre.org/ [11312.017635] Lustre: DEBUG MARKER: Iteration 4 [11312.392102] LustreError: 348574:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11312.398618] LustreError: 348576:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11312.407732] LustreError: 348574:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11313.804966] Lustre: Mounted lustre-client [11315.063237] LustreError: 348683:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4b8558000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11315.088236] LustreError: 348683:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11315.217216] Lustre: Unmounted lustre-client [11317.491575] Key type lgssc unregistered [11317.772375] LNet: 348933:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11317.789677] LNetError: 348933:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11317.829898] LNet: Removed LNI 192.168.204.58@tcp [11318.374206] Key type .llcrypt unregistered [11318.378639] Key type ._llcrypt unregistered [11319.024656] Key type ._llcrypt registered [11319.027577] Key type .llcrypt registered [11319.398524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11319.411684] alg: No test for adler32 (adler32-zlib) [11320.420885] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11320.596460] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11322.239156] Key type lgssc registered [11323.482806] Lustre: Echo OBD driver; http://www.lustre.org/ [11333.533197] Lustre: DEBUG MARKER: Iteration 5 [11333.922794] LustreError: 349763:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11333.922810] LustreError: 349764:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11333.945340] LustreError: 349763:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11336.204173] Lustre: Mounted lustre-client [11337.318581] LustreError: 349871:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4843f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11337.346918] LustreError: 349871:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11337.356708] LustreError: 349871:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11337.432209] Lustre: Unmounted lustre-client [11340.079274] Key type lgssc unregistered [11340.404164] LNet: 350120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11340.417240] LNetError: 350120:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11341.481198] LNet: Removed LNI 192.168.204.58@tcp [11342.259131] Key type .llcrypt unregistered [11342.260759] Key type ._llcrypt unregistered [11342.900947] Key type ._llcrypt registered [11342.902407] Key type .llcrypt registered [11343.470354] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11343.485437] alg: No test for adler32 (adler32-zlib) [11344.562769] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11344.852106] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11346.623386] Key type lgssc registered [11348.173522] Lustre: Echo OBD driver; http://www.lustre.org/ [11360.979543] Lustre: DEBUG MARKER: Iteration 6 [11361.363473] LustreError: 350951:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11361.364693] LustreError: 350950:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11361.372560] LustreError: 350951:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11362.727507] Lustre: Mounted lustre-client [11364.177837] LustreError: 351061:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0663800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11364.196450] LustreError: 351061:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11364.272880] Lustre: Unmounted lustre-client [11364.285922] Lustre: Skipped 1 previous similar message [11366.819726] Key type lgssc unregistered [11367.047712] LNet: 351310:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11367.057153] LNetError: 351310:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11367.072473] LNet: Removed LNI 192.168.204.58@tcp [11367.794496] Key type .llcrypt unregistered [11367.796732] Key type ._llcrypt unregistered [11368.324271] Key type ._llcrypt registered [11368.325557] Key type .llcrypt registered [11368.883796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11368.909637] alg: No test for adler32 (adler32-zlib) [11370.078897] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11370.301400] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11371.983974] Key type lgssc registered [11373.360181] Lustre: Echo OBD driver; http://www.lustre.org/ [11383.581057] Lustre: DEBUG MARKER: Iteration 7 [11384.043252] LustreError: 352154:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11384.047032] LustreError: 352153:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11384.055280] LustreError: 352154:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11386.292980] Lustre: Mounted lustre-client [11387.522631] LustreError: 352266:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d486cd9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11387.543730] LustreError: 352266:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11387.630251] Lustre: Unmounted lustre-client [11390.083719] Key type lgssc unregistered [11390.304726] LNet: 352511:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11390.316125] LNetError: 352511:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11390.342269] LNet: Removed LNI 192.168.204.58@tcp [11391.000695] Key type .llcrypt unregistered [11391.008426] Key type ._llcrypt unregistered [11391.954234] Key type ._llcrypt registered [11391.955959] Key type .llcrypt registered [11392.296765] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11392.308169] alg: No test for adler32 (adler32-zlib) [11393.413253] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11393.603741] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11395.272413] Key type lgssc registered [11396.571534] Lustre: Echo OBD driver; http://www.lustre.org/ [11407.493387] Lustre: DEBUG MARKER: Iteration 8 [11407.856593] LustreError: 353342:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11407.859603] LustreError: 353343:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11407.870194] LustreError: 353342:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11409.195570] Lustre: Mounted lustre-client [11410.296304] LustreError: 353456:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4903b0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11410.312315] LustreError: 353456:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11410.411308] Lustre: Unmounted lustre-client [11412.991935] Key type lgssc unregistered [11413.259357] LNet: 353706:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11413.271291] LNetError: 353706:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11414.311133] LNet: Removed LNI 192.168.204.58@tcp [11414.866249] Key type .llcrypt unregistered [11414.867880] Key type ._llcrypt unregistered [11415.472790] Key type ._llcrypt registered [11415.474354] Key type .llcrypt registered [11415.852212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11415.865988] alg: No test for adler32 (adler32-zlib) [11416.957414] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11417.256575] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11418.978580] Key type lgssc registered [11420.050894] Lustre: Echo OBD driver; http://www.lustre.org/ [11429.956666] Lustre: DEBUG MARKER: Iteration 9 [11430.222103] LustreError: 354536:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11430.224370] LustreError: 354537:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11430.234177] LustreError: 354536:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11431.508346] Lustre: Mounted lustre-client [11432.657710] LustreError: 354652:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0f5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11432.676237] LustreError: 354652:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11432.755511] Lustre: Unmounted lustre-client [11434.917767] Key type lgssc unregistered [11435.138308] LNet: 354895:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11435.147490] LNetError: 354895:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11435.161548] LNet: Removed LNI 192.168.204.58@tcp [11435.778184] Key type .llcrypt unregistered [11435.780092] Key type ._llcrypt unregistered [11436.509089] Key type ._llcrypt registered [11436.524227] Key type .llcrypt registered [11436.898974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11436.924111] alg: No test for adler32 (adler32-zlib) [11438.085700] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11438.281983] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11439.927340] Key type lgssc registered [11440.889231] Lustre: Echo OBD driver; http://www.lustre.org/ [11451.563947] Lustre: DEBUG MARKER: Iteration 10 [11451.803811] LustreError: 355725:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11451.811476] LustreError: 355724:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11451.817097] LustreError: 355725:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11453.178995] Lustre: Mounted lustre-client [11454.598903] LustreError: 355831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4900da800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11454.626667] LustreError: 355831:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11454.719982] Lustre: Unmounted lustre-client [11457.517455] Key type lgssc unregistered [11457.740219] LNet: 356080:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11457.751458] LNetError: 356080:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11458.816572] LNet: Removed LNI 192.168.204.58@tcp [11459.637435] Key type .llcrypt unregistered [11459.641480] Key type ._llcrypt unregistered [11461.098158] Key type ._llcrypt registered [11461.099712] Key type .llcrypt registered [11461.352872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11461.365032] alg: No test for adler32 (adler32-zlib) [11462.420658] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11462.605135] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11464.351208] Key type lgssc registered [11465.579482] Lustre: Echo OBD driver; http://www.lustre.org/ [11476.058180] Lustre: DEBUG MARKER: Iteration 11 [11476.505265] LustreError: 356911:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11476.510306] LustreError: 356912:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11476.522607] LustreError: 356911:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11477.891382] Lustre: Mounted lustre-client [11479.227532] LustreError: 357026:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4830bb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11479.235075] LustreError: 357026:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11479.265285] LustreError: 357021:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11479.347351] Lustre: Unmounted lustre-client [11481.693527] Key type lgssc unregistered [11481.971146] LNet: 357267:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11481.976758] LNetError: 357267:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11481.994872] LNet: Removed LNI 192.168.204.58@tcp [11482.589355] Key type .llcrypt unregistered [11482.593400] Key type ._llcrypt unregistered [11483.366949] Key type ._llcrypt registered [11483.368392] Key type .llcrypt registered [11483.555619] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11483.566621] alg: No test for adler32 (adler32-zlib) [11484.585115] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11484.792902] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11486.439245] Key type lgssc registered [11488.210570] Lustre: Echo OBD driver; http://www.lustre.org/ [11499.509836] Lustre: DEBUG MARKER: Iteration 12 [11499.832172] LustreError: 358094:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11499.857634] LustreError: 358114:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11499.861990] LustreError: 358094:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4978 [11501.136509] Lustre: Mounted lustre-client [11502.388971] LustreError: 358207:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d490dfb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11502.413524] LustreError: 358207:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11502.476936] Lustre: Unmounted lustre-client [11504.843712] Key type lgssc unregistered [11505.067895] LNet: 358456:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11505.074203] LNetError: 358456:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11505.092433] LNet: Removed LNI 192.168.204.58@tcp [11505.659934] Key type .llcrypt unregistered [11505.661628] Key type ._llcrypt unregistered [11506.356649] Key type ._llcrypt registered [11506.358316] Key type .llcrypt registered [11506.671736] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11506.692619] alg: No test for adler32 (adler32-zlib) [11507.710373] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11507.938108] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11509.623194] Key type lgssc registered [11510.776500] Lustre: Echo OBD driver; http://www.lustre.org/ [11520.858714] Lustre: DEBUG MARKER: Iteration 13 [11521.174062] LustreError: 359285:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11521.177335] LustreError: 359287:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11521.187206] LustreError: 359285:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11522.482628] Lustre: Mounted lustre-client [11523.737543] LustreError: 359396:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d484d62000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11523.762591] LustreError: 359396:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11523.813431] Lustre: Unmounted lustre-client [11526.135541] Key type lgssc unregistered [11526.370742] LNet: 359641:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11526.376569] LNetError: 359641:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11526.407137] LNet: Removed LNI 192.168.204.58@tcp [11527.076148] Key type .llcrypt unregistered [11527.077808] Key type ._llcrypt unregistered [11527.785589] Key type ._llcrypt registered [11527.787249] Key type .llcrypt registered [11528.074283] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11528.084481] alg: No test for adler32 (adler32-zlib) [11529.129615] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11529.328574] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11530.999272] Key type lgssc registered [11532.184573] Lustre: Echo OBD driver; http://www.lustre.org/ [11542.496818] Lustre: DEBUG MARKER: Iteration 14 [11542.791624] LustreError: 360471:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11542.794813] LustreError: 360472:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11542.803676] LustreError: 360471:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11544.119426] Lustre: Mounted lustre-client [11545.467732] LustreError: 360585:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d486789800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11545.482822] LustreError: 360589:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11545.495164] LustreError: 360589:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11545.537440] Lustre: Unmounted lustre-client [11548.442478] Key type lgssc unregistered [11548.700278] LNet: 360830:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11548.705478] LNetError: 360830:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11549.736479] LNet: Removed LNI 192.168.204.58@tcp [11550.356655] Key type .llcrypt unregistered [11550.363255] Key type ._llcrypt unregistered [11551.180861] Key type ._llcrypt registered [11551.188847] Key type .llcrypt registered [11551.431978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11551.448327] alg: No test for adler32 (adler32-zlib) [11552.539822] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11552.772988] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11554.488465] Key type lgssc registered [11555.677262] Lustre: Echo OBD driver; http://www.lustre.org/ [11566.713371] Lustre: DEBUG MARKER: Iteration 15 [11567.138044] LustreError: 361661:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11567.138060] LustreError: 361660:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11567.149944] LustreError: 361661:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [11568.463429] Lustre: Mounted lustre-client [11569.757978] LustreError: 361767:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d490dff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11569.784794] LustreError: 361767:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11569.893413] Lustre: Unmounted lustre-client [11572.559515] Key type lgssc unregistered [11572.902994] LNet: 362017:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11572.921543] LNetError: 362017:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11572.946109] LNet: Removed LNI 192.168.204.58@tcp [11573.734160] Key type .llcrypt unregistered [11573.738227] Key type ._llcrypt unregistered [11574.561740] Key type ._llcrypt registered [11574.563519] Key type .llcrypt registered [11575.097617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11575.111110] alg: No test for adler32 (adler32-zlib) [11576.223900] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11576.464940] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11578.127158] Key type lgssc registered [11579.333914] Lustre: Echo OBD driver; http://www.lustre.org/ [11589.647112] Lustre: DEBUG MARKER: Iteration 16 [11590.036362] LustreError: 362846:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11590.036678] LustreError: 362847:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11590.050163] LustreError: 362846:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11591.345972] Lustre: Mounted lustre-client [11592.698750] LustreError: 362958:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0f5a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11592.719268] LustreError: 362958:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11592.799247] Lustre: Unmounted lustre-client [11595.357622] Key type lgssc unregistered [11595.624916] LNet: 363202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11595.634348] LNetError: 363202:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11595.666210] LNet: Removed LNI 192.168.204.58@tcp [11596.256137] Key type .llcrypt unregistered [11596.266851] Key type ._llcrypt unregistered [11597.015670] Key type ._llcrypt registered [11597.017585] Key type .llcrypt registered [11597.329782] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11597.340865] alg: No test for adler32 (adler32-zlib) [11598.397446] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11598.604872] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11600.287101] Key type lgssc registered [11601.444606] Lustre: Echo OBD driver; http://www.lustre.org/ [11611.357299] Lustre: DEBUG MARKER: Iteration 17 [11611.698837] LustreError: 364031:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11611.699113] LustreError: 364029:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11611.725047] LustreError: 364029:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11613.058642] Lustre: Mounted lustre-client [11614.149162] LustreError: 364143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4be7a8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11614.162296] LustreError: 364143:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11614.215234] Lustre: Unmounted lustre-client [11616.637434] Key type lgssc unregistered [11617.038267] LNet: 364384:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11617.055292] LNetError: 364384:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11617.072830] LNet: Removed LNI 192.168.204.58@tcp [11617.768159] Key type .llcrypt unregistered [11617.771332] Key type ._llcrypt unregistered [11618.377878] Key type ._llcrypt registered [11618.386493] Key type .llcrypt registered [11619.057369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11619.085101] alg: No test for adler32 (adler32-zlib) [11620.166207] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11620.425786] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11622.111431] Key type lgssc registered [11623.403411] Lustre: Echo OBD driver; http://www.lustre.org/ [11634.538290] Lustre: DEBUG MARKER: Iteration 18 [11634.879391] LustreError: 365213:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11634.887961] LustreError: 365218:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11634.893375] LustreError: 365213:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11636.294687] Lustre: Mounted lustre-client [11637.587807] LustreError: 365328:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d490dfa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11637.604067] LustreError: 365328:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11637.607915] LustreError: 365328:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11637.675563] Lustre: Unmounted lustre-client [11641.095502] Key type lgssc unregistered [11641.366472] LNet: 365569:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11641.379904] LNetError: 365569:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11641.408536] LNet: Removed LNI 192.168.204.58@tcp [11642.039452] Key type .llcrypt unregistered [11642.043588] Key type ._llcrypt unregistered [11642.803645] Key type ._llcrypt registered [11642.805326] Key type .llcrypt registered [11643.302752] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11643.311883] alg: No test for adler32 (adler32-zlib) [11644.535662] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11644.796829] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11646.503204] Key type lgssc registered [11647.790062] Lustre: Echo OBD driver; http://www.lustre.org/ [11656.942763] Lustre: DEBUG MARKER: Iteration 19 [11657.287288] LustreError: 366399:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11657.290683] LustreError: 366400:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11657.299821] LustreError: 366399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11658.534960] Lustre: Mounted lustre-client [11659.532697] LustreError: 366505:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d49872b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11659.548031] LustreError: 366505:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11659.647746] Lustre: Unmounted lustre-client [11661.736585] Key type lgssc unregistered [11661.955585] LNet: 366753:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11661.961920] LNetError: 366753:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11661.978075] LNet: Removed LNI 192.168.204.58@tcp [11662.571171] Key type .llcrypt unregistered [11662.572685] Key type ._llcrypt unregistered [11662.952617] Key type ._llcrypt registered [11662.954116] Key type .llcrypt registered [11663.472017] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11663.481243] alg: No test for adler32 (adler32-zlib) [11664.566234] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11664.804301] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11666.503202] Key type lgssc registered [11667.781918] Lustre: Echo OBD driver; http://www.lustre.org/ [11677.373827] Lustre: DEBUG MARKER: Iteration 20 [11677.592219] LustreError: 367579:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11677.594096] LustreError: 367581:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11677.612386] LustreError: 367579:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11678.867621] Lustre: Mounted lustre-client [11679.901156] LustreError: 367696:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d489f89800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11679.908181] LustreError: 367696:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11679.915117] LustreError: 367693:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11679.919398] LustreError: 367693:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11679.984254] Lustre: Unmounted lustre-client [11681.733168] Key type lgssc unregistered [11681.919755] LNet: 367938:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11681.924537] LNetError: 367938:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11682.983440] LNet: Removed LNI 192.168.204.58@tcp [11683.494271] Key type .llcrypt unregistered [11683.495966] Key type ._llcrypt unregistered [11684.193922] Key type ._llcrypt registered [11684.195750] Key type .llcrypt registered [11684.681597] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11684.702318] alg: No test for adler32 (adler32-zlib) [11685.826891] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11686.054626] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11687.751160] Key type lgssc registered [11689.129668] Lustre: Echo OBD driver; http://www.lustre.org/ [11699.986140] Lustre: DEBUG MARKER: Iteration 21 [11700.401789] LustreError: 368767:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11700.403280] LustreError: 368768:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11700.425087] LustreError: 368767:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11702.764825] Lustre: Mounted lustre-client [11704.055019] LustreError: 368875:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d498b12800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11704.073870] LustreError: 368875:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11704.135382] Lustre: Unmounted lustre-client [11706.893614] Key type lgssc unregistered [11707.260863] LNet: 369124:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11707.265121] LNetError: 369124:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11707.294467] LNet: Removed LNI 192.168.204.58@tcp [11707.986188] Key type .llcrypt unregistered [11707.987984] Key type ._llcrypt unregistered [11708.802267] Key type ._llcrypt registered [11708.803637] Key type .llcrypt registered [11709.123161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11709.137061] alg: No test for adler32 (adler32-zlib) [11710.212586] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11710.437983] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11712.127304] Key type lgssc registered [11713.265568] Lustre: Echo OBD driver; http://www.lustre.org/ [11724.100288] Lustre: DEBUG MARKER: Iteration 22 [11724.373637] LustreError: 369952:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11724.376530] LustreError: 369954:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11724.421614] LustreError: 369952:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4960 [11725.694494] Lustre: Mounted lustre-client [11726.802789] LustreError: 370065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4bcb01000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11726.818816] LustreError: 370065:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11726.904228] Lustre: Unmounted lustre-client [11729.086693] Key type lgssc unregistered [11729.361483] LNet: 370315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11729.372762] LNetError: 370315:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11729.389705] LNet: Removed LNI 192.168.204.58@tcp [11729.991344] Key type .llcrypt unregistered [11729.994053] Key type ._llcrypt unregistered [11730.952876] Key type ._llcrypt registered [11730.969046] Key type .llcrypt registered [11731.150968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11731.165227] alg: No test for adler32 (adler32-zlib) [11732.260857] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11732.514966] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11734.167245] Key type lgssc registered [11735.215617] Lustre: Echo OBD driver; http://www.lustre.org/ [11743.148626] Lustre: DEBUG MARKER: Iteration 23 [11743.498380] LustreError: 371145:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11743.501558] LustreError: 371146:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11743.512221] LustreError: 371145:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11744.831661] Lustre: Mounted lustre-client [11744.834196] Lustre: Skipped 1 previous similar message [11745.911119] LustreError: 371259:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4903e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11745.921284] LustreError: 371259:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11745.925237] LustreError: 371263:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11746.003109] Lustre: Unmounted lustre-client [11748.025710] Key type lgssc unregistered [11748.195651] LNet: 371505:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11748.203645] LNetError: 371505:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11748.223250] LNet: Removed LNI 192.168.204.58@tcp [11748.727175] Key type .llcrypt unregistered [11748.734235] Key type ._llcrypt unregistered [11749.202599] Key type ._llcrypt registered [11749.204117] Key type .llcrypt registered [11749.516923] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11749.529460] alg: No test for adler32 (adler32-zlib) [11750.560232] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11750.727082] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11752.367320] Key type lgssc registered [11753.767817] Lustre: Echo OBD driver; http://www.lustre.org/ [11762.742504] Lustre: DEBUG MARKER: Iteration 24 [11763.051058] LustreError: 372334:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11763.051592] LustreError: 372336:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11763.079713] LustreError: 372334:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [11764.343399] Lustre: Mounted lustre-client [11765.439541] LustreError: 372452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4b16d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11765.451250] LustreError: 372452:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11765.453188] LustreError: 372453:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11765.510384] Lustre: Unmounted lustre-client [11768.202660] Key type lgssc unregistered [11768.607136] LNet: 372694:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11768.617916] LNetError: 372694:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11768.659285] LNet: Removed LNI 192.168.204.58@tcp [11769.430243] Key type .llcrypt unregistered [11769.434949] Key type ._llcrypt unregistered [11770.199186] Key type ._llcrypt registered [11770.200816] Key type .llcrypt registered [11770.575695] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11770.599460] alg: No test for adler32 (adler32-zlib) [11771.642676] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11771.869058] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11773.551488] Key type lgssc registered [11774.959878] Lustre: Echo OBD driver; http://www.lustre.org/ [11785.373945] Lustre: DEBUG MARKER: Iteration 25 [11785.654928] LustreError: 373523:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11785.655520] LustreError: 373526:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11785.668603] LustreError: 373523:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11786.945200] Lustre: Mounted lustre-client [11788.152472] LustreError: 373636:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d488883800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11788.174287] LustreError: 373636:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11788.177920] LustreError: 373636:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11788.220516] Lustre: Unmounted lustre-client [11790.035294] Key type lgssc unregistered [11790.263666] LNet: 373883:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11790.270207] LNetError: 373883:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11790.285166] LNet: Removed LNI 192.168.204.58@tcp [11790.773150] Key type .llcrypt unregistered [11790.774849] Key type ._llcrypt unregistered [11791.363634] Key type ._llcrypt registered [11791.367416] Key type .llcrypt registered [11791.880881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11791.894777] alg: No test for adler32 (adler32-zlib) [11792.976773] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11793.283653] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11794.967160] Key type lgssc registered [11796.582598] Lustre: Echo OBD driver; http://www.lustre.org/ [11805.892186] Lustre: DEBUG MARKER: Iteration 26 [11806.314040] LustreError: 374714:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11806.317469] LustreError: 374715:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11806.326906] LustreError: 374714:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11807.630131] Lustre: Mounted lustre-client [11807.632135] Lustre: Skipped 1 previous similar message [11808.785733] LustreError: 374826:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d488372000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11808.816120] LustreError: 374826:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11808.876637] Lustre: Unmounted lustre-client [11811.461246] Key type lgssc unregistered [11811.678783] LNet: 375075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11811.686726] LNetError: 375075:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11811.700712] LNet: Removed LNI 192.168.204.58@tcp [11812.277540] Key type .llcrypt unregistered [11812.282502] Key type ._llcrypt unregistered [11812.877105] Key type ._llcrypt registered [11812.878692] Key type .llcrypt registered [11813.322142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11813.333925] alg: No test for adler32 (adler32-zlib) [11814.402502] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11814.576803] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11816.256919] Key type lgssc registered [11817.456456] Lustre: Echo OBD driver; http://www.lustre.org/ [11827.491904] Lustre: DEBUG MARKER: Iteration 27 [11827.866351] LustreError: 375903:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11827.874377] LustreError: 375904:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11827.888607] LustreError: 375903:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11829.171974] Lustre: Mounted lustre-client [11830.226294] LustreError: 376013:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4b2a72800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11830.248728] LustreError: 376013:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11830.297113] Lustre: Unmounted lustre-client [11830.374618] Lustre: Skipped 1 previous similar message [11832.773372] Key type lgssc unregistered [11833.036733] LNet: 376261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11833.049182] LNetError: 376261:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11833.064241] LNet: Removed LNI 192.168.204.58@tcp [11833.659393] Key type .llcrypt unregistered [11833.662965] Key type ._llcrypt unregistered [11834.348721] Key type ._llcrypt registered [11834.350407] Key type .llcrypt registered [11834.726823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11834.747838] alg: No test for adler32 (adler32-zlib) [11835.764872] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11835.960642] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11837.623204] Key type lgssc registered [11838.833480] Lustre: Echo OBD driver; http://www.lustre.org/ [11848.998550] Lustre: DEBUG MARKER: Iteration 28 [11849.309820] LustreError: 377088:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11849.315187] LustreError: 377097:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11849.319659] LustreError: 377088:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11850.648522] Lustre: Mounted lustre-client [11852.018905] LustreError: 377209:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4843d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11852.045845] LustreError: 377209:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11852.131359] Lustre: Unmounted lustre-client [11854.799953] Key type lgssc unregistered [11855.047798] LNet: 377452:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11855.061654] LNetError: 377452:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11855.089585] LNet: Removed LNI 192.168.204.58@tcp [11855.703174] Key type .llcrypt unregistered [11855.707065] Key type ._llcrypt unregistered [11856.450502] Key type ._llcrypt registered [11856.457621] Key type .llcrypt registered [11857.106513] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11857.120021] alg: No test for adler32 (adler32-zlib) [11858.199213] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11858.365438] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11860.047167] Key type lgssc registered [11861.100780] Lustre: Echo OBD driver; http://www.lustre.org/ [11871.020744] Lustre: DEBUG MARKER: Iteration 29 [11871.510801] LustreError: 378282:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11871.522296] LustreError: 378287:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11871.529118] LustreError: 378282:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11872.812805] Lustre: Mounted lustre-client [11872.814681] Lustre: Skipped 1 previous similar message [11874.218120] LustreError: 378386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d490dfe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11874.268198] LustreError: 378386:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11874.325927] Lustre: Unmounted lustre-client [11876.834237] Key type lgssc unregistered [11877.039706] LNet: 378636:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11877.046966] LNetError: 378636:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11877.063514] LNet: Removed LNI 192.168.204.58@tcp [11877.749710] Key type .llcrypt unregistered [11877.755618] Key type ._llcrypt unregistered [11878.746907] Key type ._llcrypt registered [11878.775968] Key type .llcrypt registered [11879.012895] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11879.036672] alg: No test for adler32 (adler32-zlib) [11880.294241] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11880.559518] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11882.319170] Key type lgssc registered [11883.912552] Lustre: Echo OBD driver; http://www.lustre.org/ [11893.442563] Lustre: DEBUG MARKER: Iteration 30 [11893.828944] LustreError: 379465:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11893.833722] LustreError: 379467:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11893.867747] LustreError: 379465:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [11896.225191] Lustre: Mounted lustre-client [11897.956876] LustreError: 379574:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0e25800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11897.987510] LustreError: 379574:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11898.068594] Lustre: Unmounted lustre-client [11900.432520] Key type lgssc unregistered [11900.671907] LNet: 379822:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11900.676898] LNetError: 379822:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11900.686673] LNet: Removed LNI 192.168.204.58@tcp [11901.380153] Key type .llcrypt unregistered [11901.390149] Key type ._llcrypt unregistered [11902.554889] Key type ._llcrypt registered [11902.559370] Key type .llcrypt registered [11902.827340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11902.845423] alg: No test for adler32 (adler32-zlib) [11903.892808] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11904.090720] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11905.743279] Key type lgssc registered [11907.227756] Lustre: Echo OBD driver; http://www.lustre.org/ [11918.602416] Lustre: DEBUG MARKER: Iteration 31 [11918.993307] LustreError: 380654:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11918.997637] LustreError: 380656:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11919.012101] LustreError: 380654:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11920.382282] Lustre: Mounted lustre-client [11921.526693] LustreError: 380761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d485006800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11921.542619] LustreError: 380761:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11921.625358] Lustre: Unmounted lustre-client [11923.727607] Key type lgssc unregistered [11923.925753] LNet: 381011:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11923.931872] LNetError: 381011:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11923.951661] LNet: Removed LNI 192.168.204.58@tcp [11924.492159] Key type .llcrypt unregistered [11924.493791] Key type ._llcrypt unregistered [11924.982433] Key type ._llcrypt registered [11924.984077] Key type .llcrypt registered [11925.396231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11925.407921] alg: No test for adler32 (adler32-zlib) [11926.463917] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11926.638314] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11928.295172] Key type lgssc registered [11929.519491] Lustre: Echo OBD driver; http://www.lustre.org/ [11939.181664] Lustre: DEBUG MARKER: Iteration 32 [11939.509091] LustreError: 381841:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11939.512503] LustreError: 381842:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11939.523248] LustreError: 381841:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11940.725403] Lustre: Mounted lustre-client [11941.859564] LustreError: 381952:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4984ef000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11941.876493] LustreError: 381952:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11941.883469] LustreError: 381952:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11941.941089] Lustre: Unmounted lustre-client [11944.246660] Key type lgssc unregistered [11944.503989] LNet: 382196:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11944.515255] LNetError: 382196:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11944.541907] LNet: Removed LNI 192.168.204.58@tcp [11945.177149] Key type .llcrypt unregistered [11945.180839] Key type ._llcrypt unregistered [11945.854310] Key type ._llcrypt registered [11945.855947] Key type .llcrypt registered [11946.241844] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11946.251947] alg: No test for adler32 (adler32-zlib) [11947.377403] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11947.625910] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11949.319301] Key type lgssc registered [11950.332469] Lustre: Echo OBD driver; http://www.lustre.org/ [11960.965045] Lustre: DEBUG MARKER: Iteration 33 [11961.299489] LustreError: 383024:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11961.299965] LustreError: 383025:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11961.314578] LustreError: 383024:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11962.566670] Lustre: Mounted lustre-client [11963.688932] LustreError: 383133:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4baaab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11963.718904] LustreError: 383133:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11963.767826] Lustre: Unmounted lustre-client [11965.777479] Key type lgssc unregistered [11966.004971] LNet: 383381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11966.009962] LNetError: 383381:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11967.081382] LNet: Removed LNI 192.168.204.58@tcp [11967.600337] Key type .llcrypt unregistered [11967.602891] Key type ._llcrypt unregistered [11968.077699] Key type ._llcrypt registered [11968.080850] Key type .llcrypt registered [11968.350218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11968.362446] alg: No test for adler32 (adler32-zlib) [11969.385109] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11969.520932] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11971.151712] Key type lgssc registered [11971.979543] Lustre: Echo OBD driver; http://www.lustre.org/ [11979.463052] Lustre: DEBUG MARKER: Iteration 34 [11979.656834] LustreError: 384210:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11979.674947] LustreError: 384216:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11979.679400] LustreError: 384210:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4977 [11980.872208] Lustre: Mounted lustre-client [11981.970338] LustreError: 384326:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4839f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11981.976243] LustreError: 384326:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11981.985149] LustreError: 384326:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11981.989794] LustreError: 384326:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11982.030875] Lustre: Unmounted lustre-client [11983.684224] Key type lgssc unregistered [11983.886926] LNet: 384571:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11983.900916] LNetError: 384571:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11983.916552] LNet: Removed LNI 192.168.204.58@tcp [11984.447189] Key type .llcrypt unregistered [11984.452028] Key type ._llcrypt unregistered [11984.972957] Key type ._llcrypt registered [11984.974685] Key type .llcrypt registered [11985.366850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11985.382882] alg: No test for adler32 (adler32-zlib) [11986.339184] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [11986.543864] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [11988.202325] Key type lgssc registered [11989.061606] Lustre: Echo OBD driver; http://www.lustre.org/ [11996.974427] Lustre: DEBUG MARKER: Iteration 35 [11997.306597] LustreError: 385401:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11997.309500] LustreError: 385402:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11997.317549] LustreError: 385401:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11998.526146] Lustre: Mounted lustre-client [11999.524694] LustreError: 385512:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d498b12000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11999.541955] LustreError: 385512:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11999.596508] Lustre: Unmounted lustre-client [12001.846913] Key type lgssc unregistered [12002.042898] LNet: 385760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12002.055665] LNetError: 385760:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12003.113949] LNet: Removed LNI 192.168.204.58@tcp [12003.615205] Key type .llcrypt unregistered [12003.616961] Key type ._llcrypt unregistered [12004.177974] Key type ._llcrypt registered [12004.182236] Key type .llcrypt registered [12004.455241] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12004.478405] alg: No test for adler32 (adler32-zlib) [12005.494443] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12005.675561] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12007.327202] Key type lgssc registered [12008.240769] Lustre: Echo OBD driver; http://www.lustre.org/ [12015.762473] Lustre: DEBUG MARKER: Iteration 36 [12015.984898] LustreError: 386588:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12015.986258] LustreError: 386587:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12015.992109] LustreError: 386588:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12017.185615] Lustre: Mounted lustre-client [12018.303215] LustreError: 386701:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4984a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12018.326550] LustreError: 386701:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [12018.397499] Lustre: Unmounted lustre-client [12020.302602] Key type lgssc unregistered [12020.501125] LNet: 386945:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12020.505576] LNetError: 386945:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12020.519790] LNet: Removed LNI 192.168.204.58@tcp [12021.036153] Key type .llcrypt unregistered [12021.037859] Key type ._llcrypt unregistered [12021.524568] Key type ._llcrypt registered [12021.527305] Key type .llcrypt registered [12021.809242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12021.820533] alg: No test for adler32 (adler32-zlib) [12022.764373] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12022.928242] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12024.575190] Key type lgssc registered [12025.345753] Lustre: Echo OBD driver; http://www.lustre.org/ [12033.432569] Lustre: DEBUG MARKER: Iteration 37 [12033.738490] LustreError: 387773:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12033.738536] LustreError: 387774:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12033.750172] LustreError: 387773:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12034.957211] Lustre: Mounted lustre-client [12036.006064] LustreError: 387882:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0375000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12036.028386] LustreError: 387883:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12036.031499] LustreError: 387883:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12036.074566] Lustre: Unmounted lustre-client [12038.109436] Key type lgssc unregistered [12038.375901] LNet: 388123:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12038.384793] LNetError: 388123:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12038.410871] LNet: Removed LNI 192.168.204.58@tcp [12038.949197] Key type .llcrypt unregistered [12038.951199] Key type ._llcrypt unregistered [12039.578235] Key type ._llcrypt registered [12039.582914] Key type .llcrypt registered [12039.904527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12039.920503] alg: No test for adler32 (adler32-zlib) [12040.955249] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12041.149714] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12042.847166] Key type lgssc registered [12043.736296] Lustre: Echo OBD driver; http://www.lustre.org/ [12051.986823] Lustre: DEBUG MARKER: Iteration 38 [12052.178937] LustreError: 388953:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12052.180125] LustreError: 388954:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12052.189900] LustreError: 388953:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12053.384367] Lustre: Mounted lustre-client [12053.385630] Lustre: Skipped 1 previous similar message [12054.230863] LustreError: 389069:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4831d7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12054.251196] LustreError: 389069:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12054.255322] LustreError: 389069:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12054.314412] Lustre: Unmounted lustre-client [12056.401525] Key type lgssc unregistered [12056.650454] LNet: 389310:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12056.656328] LNetError: 389310:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12057.704884] LNet: Removed LNI 192.168.204.58@tcp [12058.289271] Key type .llcrypt unregistered [12058.293739] Key type ._llcrypt unregistered [12058.962321] Key type ._llcrypt registered [12058.964216] Key type .llcrypt registered [12059.370439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12059.380716] alg: No test for adler32 (adler32-zlib) [12060.445199] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12060.629804] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12062.279443] Key type lgssc registered [12063.282301] Lustre: Echo OBD driver; http://www.lustre.org/ [12071.852991] Lustre: DEBUG MARKER: Iteration 39 [12072.045429] LustreError: 390137:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12072.050128] LustreError: 390140:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12072.062321] LustreError: 390137:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12073.265315] Lustre: Mounted lustre-client [12074.215988] LustreError: 390249:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4903e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12074.236161] LustreError: 390249:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12074.293264] Lustre: Unmounted lustre-client [12076.007339] Key type lgssc unregistered [12076.187413] LNet: 390499:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12076.195061] LNetError: 390499:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12076.209169] LNet: Removed LNI 192.168.204.58@tcp [12076.700854] Key type .llcrypt unregistered [12076.702420] Key type ._llcrypt unregistered [12077.197266] Key type ._llcrypt registered [12077.203031] Key type .llcrypt registered [12077.565087] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12077.590880] alg: No test for adler32 (adler32-zlib) [12078.659680] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12078.835673] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12080.519248] Key type lgssc registered [12081.555341] Lustre: Echo OBD driver; http://www.lustre.org/ [12091.121455] Lustre: DEBUG MARKER: Iteration 40 [12091.453749] LustreError: 391326:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12091.458153] LustreError: 391328:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12091.467615] LustreError: 391326:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12092.745879] Lustre: Mounted lustre-client [12093.903656] LustreError: 391437:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d484d62000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12093.927825] LustreError: 391437:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12094.002470] Lustre: Unmounted lustre-client [12096.699503] Key type lgssc unregistered [12096.912344] LNet: 391686:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12096.925511] LNetError: 391686:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12096.944654] LNet: Removed LNI 192.168.204.58@tcp [12097.526320] Key type .llcrypt unregistered [12097.530613] Key type ._llcrypt unregistered [12098.267337] Key type ._llcrypt registered [12098.268994] Key type .llcrypt registered [12098.744650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12098.755771] alg: No test for adler32 (adler32-zlib) [12099.823962] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12100.024970] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12101.759229] Key type lgssc registered [12103.042389] Lustre: Echo OBD driver; http://www.lustre.org/ [12112.212505] Lustre: DEBUG MARKER: Iteration 41 [12112.554836] LustreError: 392516:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12112.557881] LustreError: 392517:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12112.567943] LustreError: 392516:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12113.780395] Lustre: Mounted lustre-client [12113.781875] Lustre: Skipped 1 previous similar message [12115.035881] LustreError: 392632:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4885bb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12115.044736] LustreError: 392632:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12115.052184] LustreError: 392631:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12115.109845] Lustre: Unmounted lustre-client [12117.259574] Key type lgssc unregistered [12117.559530] LNet: 392871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12117.565811] LNetError: 392871:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12117.581099] LNet: Removed LNI 192.168.204.58@tcp [12118.231263] Key type .llcrypt unregistered [12118.235344] Key type ._llcrypt unregistered [12118.804572] Key type ._llcrypt registered [12118.812054] Key type .llcrypt registered [12119.240733] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12119.254213] alg: No test for adler32 (adler32-zlib) [12120.285626] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12120.477792] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12122.135591] Key type lgssc registered [12123.159504] Lustre: Echo OBD driver; http://www.lustre.org/ [12134.354696] Lustre: DEBUG MARKER: Iteration 42 [12134.842321] LustreError: 393700:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12134.845133] LustreError: 393696:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12134.861546] LustreError: 393700:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [12136.224080] Lustre: Mounted lustre-client [12137.443046] LustreError: 393816:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4b7487800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12137.453214] LustreError: 393815:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12137.454502] LustreError: 393816:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12137.473903] LustreError: 393815:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12137.517536] Lustre: Unmounted lustre-client [12140.946107] Key type lgssc unregistered [12141.198630] LNet: 394058:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12141.214231] LNetError: 394058:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12141.240519] LNet: Removed LNI 192.168.204.58@tcp [12142.086861] Key type .llcrypt unregistered [12142.088451] Key type ._llcrypt unregistered [12142.930072] Key type ._llcrypt registered [12142.933701] Key type .llcrypt registered [12143.594208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12143.614078] alg: No test for adler32 (adler32-zlib) [12144.901724] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12145.180923] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12146.863675] Key type lgssc registered [12148.231538] Lustre: Echo OBD driver; http://www.lustre.org/ [12160.776771] Lustre: DEBUG MARKER: Iteration 43 [12161.083681] LustreError: 394898:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12161.086421] LustreError: 394902:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12161.097802] LustreError: 394898:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12162.350114] Lustre: Mounted lustre-client [12163.459072] LustreError: 395008:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4888e2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12163.479540] LustreError: 395008:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12163.519071] Lustre: Unmounted lustre-client [12166.157263] Key type lgssc unregistered [12166.434542] LNet: 395258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12166.446946] LNetError: 395258:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12166.462336] LNet: Removed LNI 192.168.204.58@tcp [12167.147211] Key type .llcrypt unregistered [12167.154159] Key type ._llcrypt unregistered [12168.027908] Key type ._llcrypt registered [12168.032110] Key type .llcrypt registered [12168.539215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12168.550130] alg: No test for adler32 (adler32-zlib) [12169.718681] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12169.979741] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12171.647540] Key type lgssc registered [12172.946623] Lustre: Echo OBD driver; http://www.lustre.org/ [12183.411392] Lustre: DEBUG MARKER: Iteration 44 [12183.965663] LustreError: 396090:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12183.965770] LustreError: 396089:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12183.981543] LustreError: 396090:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12185.306265] Lustre: Mounted lustre-client [12186.653801] LustreError: 396204:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d489f8a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12186.662123] LustreError: 396205:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12186.664745] LustreError: 396204:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12186.722809] Lustre: Unmounted lustre-client [12189.278734] Key type lgssc unregistered [12189.606751] LNet: 396446:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12189.622273] LNetError: 396446:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12189.638626] LNet: Removed LNI 192.168.204.58@tcp [12190.442859] Key type .llcrypt unregistered [12190.444607] Key type ._llcrypt unregistered [12190.935146] Key type ._llcrypt registered [12190.936974] Key type .llcrypt registered [12191.312720] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12191.348114] alg: No test for adler32 (adler32-zlib) [12192.581216] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12192.869868] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12194.591535] Key type lgssc registered [12196.051281] Lustre: Echo OBD driver; http://www.lustre.org/ [12205.527154] Lustre: DEBUG MARKER: Iteration 45 [12205.902745] LustreError: 397275:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12205.906437] LustreError: 397276:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12205.915468] LustreError: 397275:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [12207.162783] Lustre: Mounted lustre-client [12208.429508] LustreError: 397382:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d48375b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12208.462440] LustreError: 397382:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12208.550744] Lustre: Unmounted lustre-client [12210.879515] Key type lgssc unregistered [12211.119132] LNet: 397630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12211.128125] LNetError: 397630:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12211.137700] LNet: Removed LNI 192.168.204.58@tcp [12211.647176] Key type .llcrypt unregistered [12211.648882] Key type ._llcrypt unregistered [12212.564398] Key type ._llcrypt registered [12212.565890] Key type .llcrypt registered [12212.752590] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12212.767920] alg: No test for adler32 (adler32-zlib) [12213.901578] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12214.183831] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12215.858951] Key type lgssc registered [12216.868851] Lustre: Echo OBD driver; http://www.lustre.org/ [12226.679931] Lustre: DEBUG MARKER: Iteration 46 [12226.929473] LustreError: 398461:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12226.931366] LustreError: 398460:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12226.941642] LustreError: 398461:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12229.261238] Lustre: Mounted lustre-client [12230.426943] LustreError: 398569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d486aff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12230.458205] LustreError: 398569:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12230.540636] Lustre: Unmounted lustre-client [12233.943456] Key type lgssc unregistered [12234.270400] LNet: 398817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12234.290807] LNetError: 398817:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12235.303140] LNet: Removed LNI 192.168.204.58@tcp [12236.132147] Key type .llcrypt unregistered [12236.133600] Key type ._llcrypt unregistered [12236.942433] Key type ._llcrypt registered [12236.944040] Key type .llcrypt registered [12237.424585] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12237.437335] alg: No test for adler32 (adler32-zlib) [12238.517877] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12238.738872] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12240.455608] Key type lgssc registered [12241.812601] Lustre: Echo OBD driver; http://www.lustre.org/ [12253.058238] Lustre: DEBUG MARKER: Iteration 47 [12253.338860] LustreError: 399645:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12253.339329] LustreError: 399649:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12253.348060] LustreError: 399645:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12254.626241] Lustre: Mounted lustre-client [12254.635248] Lustre: Skipped 1 previous similar message [12256.157092] LustreError: 399766:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4a0373800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12256.174793] LustreError: 399766:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12256.256761] Lustre: Unmounted lustre-client [12259.079817] Key type lgssc unregistered [12259.343852] LNet: 400008:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12259.348945] LNetError: 400008:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12259.364073] LNet: Removed LNI 192.168.204.58@tcp [12260.025693] Key type .llcrypt unregistered [12260.027446] Key type ._llcrypt unregistered [12261.104260] Key type ._llcrypt registered [12261.126579] Key type .llcrypt registered [12261.367264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12261.404641] alg: No test for adler32 (adler32-zlib) [12262.399897] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12262.583250] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12264.214681] Key type lgssc registered [12265.404066] Lustre: Echo OBD driver; http://www.lustre.org/ [12277.005748] Lustre: DEBUG MARKER: Iteration 48 [12277.379582] LustreError: 400838:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12277.383369] LustreError: 400840:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12277.395682] LustreError: 400838:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [12278.697561] Lustre: Mounted lustre-client [12279.806980] LustreError: 400954:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d49102f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12279.832805] LustreError: 400954:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12279.898105] Lustre: Unmounted lustre-client [12281.953180] Key type lgssc unregistered [12282.156170] LNet: 401196:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12282.161285] LNetError: 401196:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12282.179199] LNet: Removed LNI 192.168.204.58@tcp [12282.679375] Key type .llcrypt unregistered [12282.681178] Key type ._llcrypt unregistered [12283.116113] Key type ._llcrypt registered [12283.118023] Key type .llcrypt registered [12283.514686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12283.525304] alg: No test for adler32 (adler32-zlib) [12284.486025] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12284.669520] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12286.319238] Key type lgssc registered [12287.443543] Lustre: Echo OBD driver; http://www.lustre.org/ [12296.959334] Lustre: DEBUG MARKER: Iteration 49 [12297.256481] LustreError: 402023:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12297.261474] LustreError: 402025:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12297.281245] LustreError: 402023:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12298.561359] Lustre: Mounted lustre-client [12299.761158] LustreError: 402137:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4bcd91000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12299.824837] LustreError: 402137:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12299.886104] Lustre: Unmounted lustre-client [12302.235868] Key type lgssc unregistered [12302.472934] LNet: 402387:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12302.477317] LNetError: 402387:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12302.492737] LNet: Removed LNI 192.168.204.58@tcp [12302.951158] Key type .llcrypt unregistered [12302.952733] Key type ._llcrypt unregistered [12303.505480] Key type ._llcrypt registered [12303.509974] Key type .llcrypt registered [12303.837891] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12303.851893] alg: No test for adler32 (adler32-zlib) [12304.930868] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12305.091261] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12306.719283] Key type lgssc registered [12307.763616] Lustre: Echo OBD driver; http://www.lustre.org/ [12317.397850] Lustre: DEBUG MARKER: Iteration 50 [12317.705849] LustreError: 403218:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12317.708095] LustreError: 403219:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12317.717180] LustreError: 403218:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12318.988126] Lustre: Mounted lustre-client [12320.215898] LustreError: 403331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4903e7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12320.236906] LustreError: 403331:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12320.242402] LustreError: 403331:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12320.323038] Lustre: Unmounted lustre-client [12322.497100] Key type lgssc unregistered [12322.713934] LNet: 403578:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12322.719906] LNetError: 403578:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12322.736377] LNet: Removed LNI 192.168.204.58@tcp [12323.297604] Key type .llcrypt unregistered [12323.301401] Key type ._llcrypt unregistered [12323.892712] Key type ._llcrypt registered [12323.935980] Key type .llcrypt registered [12324.165309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12324.177507] alg: No test for adler32 (adler32-zlib) [12325.252273] Lustre: Lustre: Build Version: 2.16.61_53_g0076932 [12325.472839] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [12327.166850] Key type lgssc registered [12328.360399] Lustre: Echo OBD driver; http://www.lustre.org/ [12338.300603] Lustre: Mounted lustre-client [12344.267463] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:50:32 (1763344232) [12360.672125] Lustre: 404934:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344234/real 1763344234] req@ffff95d48646df80 x1849000423599104/t0(0) o36->lustre-MDT0000-mdc-ffff95d490dff800@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344250 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12360.723912] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12360.773400] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12377.055285] Lustre: 404934:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344250/real 1763344250] req@ffff95d48646df80 x1849000423599104/t0(0) o36->lustre-MDT0000-mdc-ffff95d490dff800@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344266 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12377.076603] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12377.119227] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12393.439154] Lustre: 404934:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344266/real 1763344266] req@ffff95d48646df80 x1849000423599104/t0(0) o36->lustre-MDT0000-mdc-ffff95d490dff800@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344282 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12393.474706] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12393.521459] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12409.824298] Lustre: 404934:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344283/real 1763344283] req@ffff95d48646df80 x1849000423599104/t0(0) o36->lustre-MDT0000-mdc-ffff95d490dff800@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344299 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12409.843337] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12409.867066] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12426.207230] Lustre: 404934:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344299/real 1763344299] req@ffff95d48646df80 x1849000423599104/t0(0) o36->lustre-MDT0000-mdc-ffff95d490dff800@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344315 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12426.253601] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12426.303509] Lustre: lustre-MDT0000-mdc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12427.783896] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:51:56 (1763344316) [12438.611851] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:52:06 (1763344326) [12449.511076] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:52:17 (1763344337) [12455.367030] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:52:23 (1763344343) [12476.076322] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:52:44 (1763344364) [12507.121661] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:53:15 (1763344395) [12513.056568] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:53:21 (1763344401) [12533.727176] Lustre: 403767:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344407/real 1763344407] req@ffff95d4aad1df80 x1849000424646400/t0(0) o4->lustre-OST0000-osc-ffff95d490dff800@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344423 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12533.729301] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12533.770457] Lustre: 403767:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [12533.884556] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12550.111212] Lustre: 403766:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344423/real 1763344423] req@ffff95d489d05180 x1849000424645120/t0(0) o4->lustre-OST0000-osc-ffff95d490dff800@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344439 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [12550.115178] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12550.148504] Lustre: 403766:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 7 previous similar messages [12550.223391] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12598.111233] Lustre: 403769:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344471/real 1763344471] req@ffff95d4b6819500 x1849000424645888/t0(0) o4->lustre-OST0000-osc-ffff95d490dff800@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344487 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12598.138321] Lustre: 403769:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [12598.146741] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [12598.154080] Lustre: Skipped 2 previous similar messages [12598.188398] Lustre: lustre-OST0000-osc-ffff95d490dff800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [12598.201521] Lustre: Skipped 2 previous similar messages [12609.928153] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff95d4829de000.ost_server_uuid 50 [12611.137626] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff95d4829de000.ost_server_uuid in IDLE state after 0 sec [12612.560630] Lustre: DEBUG MARKER: cleanup: ====================================================== [12614.337983] Lustre: DEBUG MARKER: == sanityn test complete, duration 12284 sec ============= 20:55:02 (1763344502) [12615.767513] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:55:04 (1763344504) === [12896.877873] LustreError: 411214:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d4829de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12896.896621] LustreError: 411214:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12896.936816] Lustre: Unmounted lustre-client [12899.692206] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:59:48 (1763344788) === [12901.288058] LustreError: 411520:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff95d490dff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12901.303402] LustreError: 411520:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12901.342451] LustreError: 411520:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12901.345603] LustreError: 411520:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [12901.444953] Lustre: Unmounted lustre-client [12951.236395] Key type lgssc unregistered [12951.511879] LNet: 412207:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12951.519512] LNetError: 412207:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12951.561511] LNet: Removed LNI 192.168.204.58@tcp [12952.238173] Key type .llcrypt unregistered [12952.243772] Key type ._llcrypt unregistered