[ 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 499033857 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.001025] APIC: Switch to symmetric I/O mode setup [ 0.002350] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004013] kvm-guest: setup PV IPIs [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008010] pid_max: default: 32768 minimum: 301 [ 0.009120] LSM: Security Framework initializing [ 0.010046] Yama: becoming mindful. [ 0.011032] SELinux: Initializing. [ 0.012053] *** VALIDATE selinux *** [ 0.020246] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025587] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026155] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027110] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028111] *** VALIDATE tmpfs *** [ 0.029429] *** VALIDATE proc *** [ 0.030270] *** VALIDATE cgroup *** [ 0.031011] *** VALIDATE cgroup2 *** [ 0.032267] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033000] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034025] Spectre V2 : User space: Vulnerable [ 0.035007] Speculative Store Bypass: Vulnerable [ 0.037906] debug: unmapping init [mem 0xffffffff8c259000-0xffffffff8c260fff] [ 0.040189] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041674] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042022] ... version: 2 [ 0.042923] ... bit width: 48 [ 0.043012] ... generic registers: 4 [ 0.043927] ... value mask: 0000ffffffffffff [ 0.044012] ... max period: 00007fffffffffff [ 0.045008] ... fixed-purpose events: 3 [ 0.045905] ... event mask: 000000070000000f [ 0.047228] rcu: Hierarchical SRCU implementation. [ 0.049384] smp: Bringing up secondary CPUs ... [ 0.050508] x86: Booting SMP configuration: [ 0.051019] .... node #0, CPUs: #1 #2 #3 [ 0.058153] smp: Brought up 1 node, 4 CPUs [ 0.059977] smpboot: Max logical packages: 1 [ 0.060040] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.273095] node 0 deferred pages initialised in 202ms [ 0.276193] devtmpfs: initialized [ 0.277229] x86/mm: Memory block size: 128MB [ 0.279668] gcov: version magic: 0x41383552 [ 0.281190] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.282083] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.283244] pinctrl core: initialized pinctrl subsystem [ 0.284172] [ 0.284580] ************************************************************* [ 0.285017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.286015] ** ** [ 0.287013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.288016] ** ** [ 0.289018] ** This means that this kernel is built to expose internal ** [ 0.290015] ** IOMMU data structures, which may compromise security on ** [ 0.291013] ** your system. ** [ 0.292015] ** ** [ 0.293020] ** If you see this message and you are not debugging the ** [ 0.294017] ** kernel, report this immediately to your vendor! ** [ 0.295012] ** ** [ 0.296017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.297018] ************************************************************* [ 0.298881] NET: Registered protocol family 16 [ 0.299545] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.300066] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.301062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.302530] cpuidle: using governor menu [ 0.303000] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.305495] PCI: Using configuration type 1 for base access [ 0.307118] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.314111] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.315025] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.316331] cryptd: max_cpu_qlen set to 1000 [ 0.317246] ACPI: Added _OSI(Module Device) [ 0.318015] ACPI: Added _OSI(Processor Device) [ 0.319011] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.320014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.323383] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.327188] ACPI: Interpreter enabled [ 0.328049] ACPI: PM: (supports S0 S3 S4 S5) [ 0.329009] ACPI: Using IOAPIC for interrupt routing [ 0.330100] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.331364] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.340962] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.341039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.342017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.343067] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.345126] acpiphp: Slot [2] registered [ 0.346122] acpiphp: Slot [3] registered [ 0.347089] acpiphp: Slot [4] registered [ 0.348083] acpiphp: Slot [5] registered [ 0.349126] acpiphp: Slot [6] registered [ 0.350105] acpiphp: Slot [7] registered [ 0.351168] acpiphp: Slot [8] registered [ 0.352085] acpiphp: Slot [9] registered [ 0.353096] acpiphp: Slot [10] registered [ 0.354106] acpiphp: Slot [11] registered [ 0.355099] acpiphp: Slot [12] registered [ 0.356091] acpiphp: Slot [13] registered [ 0.357111] acpiphp: Slot [14] registered [ 0.358108] acpiphp: Slot [15] registered [ 0.359114] acpiphp: Slot [16] registered [ 0.360127] acpiphp: Slot [17] registered [ 0.361095] acpiphp: Slot [18] registered [ 0.362097] acpiphp: Slot [19] registered [ 0.363122] acpiphp: Slot [20] registered [ 0.364094] acpiphp: Slot [21] registered [ 0.365138] acpiphp: Slot [22] registered [ 0.366173] acpiphp: Slot [23] registered [ 0.367089] acpiphp: Slot [24] registered [ 0.368106] acpiphp: Slot [25] registered [ 0.369123] acpiphp: Slot [26] registered [ 0.370116] acpiphp: Slot [27] registered [ 0.371090] acpiphp: Slot [28] registered [ 0.372098] acpiphp: Slot [29] registered [ 0.373061] acpiphp: Slot [30] registered [ 0.374075] acpiphp: Slot [31] registered [ 0.375059] PCI host bridge to bus 0000:00 [ 0.376028] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.378048] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.380711] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.383049] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.386038] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.389068] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.391307] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.396196] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.400933] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.409583] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.412426] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.415026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.417024] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.419066] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.422520] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.425776] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.427057] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.429705] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.434020] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.444027] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.447961] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.453904] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.459020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.464018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.477023] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.485505] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.491022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.497024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.508015] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.517378] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.518414] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.521398] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.524493] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.527267] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.533122] iommu: Default domain type: Passthrough [ 0.534543] SCSI subsystem initialized [ 0.536152] ACPI: bus type USB registered [ 0.539170] usbcore: registered new interface driver usbfs [ 0.540077] usbcore: registered new interface driver hub [ 0.542094] usbcore: registered new device driver usb [ 0.544181] pps_core: LinuxPPS API ver. 1 registered [ 0.545015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.548063] PTP clock support registered [ 0.549135] EDAC MC: Ver: 3.0.0 [ 0.551264] PCI: Using ACPI for IRQ routing [ 0.552676] NetLabel: Initializing [ 0.553010] NetLabel: domain hash size = 128 [ 0.554008] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.555141] NetLabel: unlabeled traffic allowed by default [ 0.556178] vgaarb: loaded [ 0.558458] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.560011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.569000] clocksource: Switched to clocksource kvm-clock [ 0.676665] VFS: Disk quotas dquot_6.6.0 [ 0.678062] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.681334] *** VALIDATE ramfs *** [ 0.682518] *** VALIDATE hugetlbfs *** [ 0.688319] pnp: PnP ACPI init [ 0.690721] pnp: PnP ACPI: found 6 devices [ 0.714627] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.717918] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.719651] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.724704] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.726663] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.728474] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.731024] NET: Registered protocol family 2 [ 0.733940] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.759913] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.768588] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.773179] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.776140] TCP: Hash tables configured (established 65536 bind 65536) [ 0.778281] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.782799] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.784995] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.787648] NET: Registered protocol family 1 [ 0.789746] RPC: Registered named UNIX socket transport module. [ 0.791818] RPC: Registered udp transport module. [ 0.794663] RPC: Registered tcp transport module. [ 0.796128] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.797737] NET: Registered protocol family 44 [ 0.799024] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.801160] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.802833] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.804846] PCI: CLS 0 bytes, default 64 [ 0.806654] Unpacking initramfs... [ 2.509333] debug: unmapping init [mem 0xffff9c03fcc64000-0xffff9c03fffcffff] [ 2.513349] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.515379] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.517682] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.007579] Initialise system trusted keyrings [ 3.009133] Key type blacklist registered [ 3.011031] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.024871] zbud: loaded [ 3.031300] *** VALIDATE nfs *** [ 3.032852] *** VALIDATE nfs4 *** [ 3.034841] pstore: using deflate compression [ 3.039622] Platform Keyring initialized [ 3.196614] NET: Registered protocol family 38 [ 3.198438] Key type asymmetric registered [ 3.199805] Asymmetric key parser 'x509' registered [ 3.201718] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.204774] io scheduler mq-deadline registered [ 3.206830] io scheduler kyber registered [ 3.208232] io scheduler bfq registered [ 3.210404] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.212503] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.214671] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.216759] ACPI: Power Button [PWRF] [ 3.315302] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.414945] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.518234] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.547361] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.580868] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.589751] Non-volatile memory driver v1.3 [ 3.594740] Linux agpgart interface v0.103 [ 3.627100] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.629704] vda: detected capacity change from 0 to 68530176 [ 3.651179] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.654107] vdb: detected capacity change from 0 to 1073741824 [ 3.662776] libphy: Fixed MDIO Bus: probed [ 3.668268] usbcore: registered new interface driver usbserial_generic [ 3.670421] usbserial: USB Serial support registered for generic [ 3.672648] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.679289] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.681317] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.684865] mousedev: PS/2 mouse device common for all mice [ 3.689444] rtc_cmos 00:05: RTC can wake from S4 [ 3.693469] rtc_cmos 00:05: registered as rtc0 [ 3.696735] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.700506] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.701090] intel_pstate: CPU model not supported [ 3.713725] hid: raw HID events driver (C) Jiri Kosina [ 3.716635] usbcore: registered new interface driver usbhid [ 3.719410] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.720250] usbhid: USB HID core driver [ 3.720449] drop_monitor: Initializing network drop monitor service [ 3.726561] Initializing XFRM netlink socket [ 3.726689] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.728215] NET: Registered protocol family 10 [ 3.729399] Segment Routing with IPv6 [ 3.734557] NET: Registered protocol family 17 [ 3.736489] mpls_gso: MPLS GSO support [ 3.742131] RAS: Correctable Errors collector initialized. [ 3.744277] AVX version of gcm_enc/dec engaged. [ 3.745962] AES CTR mode by8 optimization enabled [ 3.854081] sched_clock: Marking stable (3854036919, 0)->(4806798023, -952761104) [ 3.858811] registered taskstats version 1 [ 3.862172] Loading compiled-in X.509 certificates [ 3.864148] zswap: loaded using pool lzo/zbud [ 3.896959] Key type big_key registered [ 3.906947] Key type encrypted registered [ 3.909265] ima: No TPM chip found, activating TPM-bypass! [ 3.911252] ima: Allocated hash algorithm: sha1 [ 3.915133] ima: No architecture policies found [ 3.916839] evm: Initialising EVM extended attributes: [ 3.918406] evm: security.selinux [ 3.919554] evm: security.ima [ 3.920431] evm: security.capability [ 3.921707] evm: HMAC attrs: 0x1 [ 3.924222] rtc_cmos 00:05: setting system clock to 2025-11-16 22:27:33 UTC (1763332053) [ 3.930609] debug: unmapping init [mem 0xffffffff8d203000-0xffffffff8d3fffff] [ 3.933582] debug: unmapping init [mem 0xffffffff8bf82000-0xffffffff8c258fff] [ 3.942220] Write protecting the kernel read-only data: 28672k [ 3.945585] debug: unmapping init [mem 0xffffffff8a603000-0xffffffff8a7fffff] [ 3.948411] debug: unmapping init [mem 0xffffffff8af14000-0xffffffff8affffff] [ 3.984127] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.992454] systemd[1]: Detected virtualization kvm. [ 3.994411] systemd[1]: Detected architecture x86-64. [ 3.996282] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.025992] systemd[1]: No hostname configured. [ 4.028060] systemd[1]: Set hostname to . [ 4.030259] random: systemd: uninitialized urandom read (16 bytes read) [ 4.033108] systemd[1]: Initializing machine ID from random generator. [ 4.204409] random: systemd: uninitialized urandom read (16 bytes read) [ 4.207127] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 4.214534] random: systemd: uninitialized urandom read (16 bytes read) [ 4.217306] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 4.222432] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Swap. Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.167075] device-mapper: uevent: version 1.0.3 [ 5.168783] 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... [ 6.343354] virtio_net virtio0 ens2: renamed from eth0 [ 6.560993] scsi host0: ata_piix [ 6.587369] scsi host1: ata_piix [ 6.588729] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.591054] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.185708] dracut-initqueue[583]: RTNETLINK answers: File exists [ 11.435832] random: crng init done [ 11.438251] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 12.733747] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.204561] printk: systemd: 26 output lines suppressed due to ratelimiting [ 14.577871] SELinux: Disabled at runtime. [ 14.655680] 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) [ 14.663663] systemd[1]: Detected virtualization kvm. [ 14.666161] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.514741] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.521223] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.529730] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.535369] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.542353] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.551954] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.559150] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create list of required st…ce nodes for the current kernel... Mounting POSIX Message Queue File System... [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ 15.655149] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. Starting Apply Kernel Variables... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Paths. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 16.465574] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 16.959728] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 17.072559] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 17.266307] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 17.418436] EDAC sbridge: Ver: 1.1.2 [ 19.222276] Key type dns_resolver registered [ 19.602182] NFS: Registering the id_resolver key type [ 19.603988] Key type id_resolver registered [ 19.607862] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg202-client login: [ 50.945025] hrtimer: interrupt took 7957063 ns [ 66.434743] libcfs: loading out-of-tree module taints kernel. [ 66.466847] Key type ._llcrypt registered [ 66.476226] Key type .llcrypt registered [ 67.058836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 67.076623] alg: No test for adler32 (adler32-zlib) [ 68.341577] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 68.993497] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [ 70.719223] Key type lgssc registered [ 72.143262] Lustre: Echo OBD driver; http://www.lustre.org/ [ 208.410542] Lustre: Mounted lustre-client [ 212.660825] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 227.981126] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing check_logdir /tmp/testlogs/ [ 232.467805] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing yml_node [ 233.955559] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 23s idle [ 237.259965] Lustre: DEBUG MARKER: Client: 2.16.61.43 [ 240.083897] Lustre: DEBUG MARKER: MDS: 2.16.61.43 [ 242.370528] Lustre: DEBUG MARKER: OSS: 2.16.61.43 [ 243.859269] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 17:31:32 EST 2025 [ 260.914923] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 262.524600] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 264.378248] Lustre: DEBUG MARKER: === sanityn: start setup 17:31:52 (1763332312) === [ 264.954592] Lustre: Mounted lustre-client [ 267.606340] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing check_config_client /mnt/lustre [ 288.630029] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 301.845855] Lustre: DEBUG MARKER: === sanityn: finish setup 17:32:29 (1763332349) === [ 304.450714] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 17:32:32 (1763332352) [ 312.207080] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 17:32:40 (1763332360) [ 318.583641] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 17:32:46 (1763332366) [ 323.494602] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 17:32:52 (1763332372) [ 328.777323] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 17:32:57 (1763332377) [ 334.183147] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 17:33:02 (1763332382) [ 339.291273] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 17:33:07 (1763332387) [ 344.888298] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 17:33:13 (1763332393) [ 351.857609] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 17:33:20 (1763332400) [ 357.804171] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 17:33:26 (1763332406) [ 363.244634] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 17:33:31 (1763332411) [ 369.650719] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 17:33:38 (1763332418) [ 372.704184] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 21s idle [ 372.706487] Lustre: Skipped 1 previous similar message [ 374.955660] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 17:33:43 (1763332423) [ 380.832970] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 17:33:49 (1763332429) [ 386.990657] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 17:33:55 (1763332435) [ 388.065381] Lustre: lustre-OST0001-osc-ffff9c0450982800: disconnect after 24s idle [ 393.305072] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 17:34:01 (1763332441) [ 399.812227] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 17:34:08 (1763332448) [ 405.469471] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 17:34:13 (1763332453) [ 411.944391] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 17:34:20 (1763332460) [ 417.922212] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 17:34:26 (1763332466) [ 423.579636] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 17:34:31 (1763332471) [ 424.249491] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 563.272717] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 17:36:52 (1763332612) [ 570.396535] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 17:36:58 (1763332618) [ 576.412524] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 17:37:04 (1763332624) [ 582.300309] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 17:37:10 (1763332630) [ 588.851696] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 17:37:17 (1763332637) [ 594.685426] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 17:37:23 (1763332643) [ 596.490577] Lustre: DEBUG MARKER: chmod [ 601.730413] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 17:37:30 (1763332650) [ 1319.903142] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: disconnect after 20s idle [ 1321.837805] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 17:49:30 (1763333370) [ 1473.504788] Lustre: lustre-OST0001-osc-ffff9c0450982800: disconnect after 23s idle [ 1473.513896] Lustre: Skipped 2 previous similar messages [ 1552.214452] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 17:53:20 (1763333600) [ 1656.450283] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 17:55:04 (1763333704) [ 1795.474562] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 17:57:24 (1763333844) [ 1824.183953] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 17:57:52 (1763333872) [ 1826.788359] Lustre: lustre-OST0001-osc-ffff9c0450982800: disconnect after 20s idle [ 1830.044693] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 17:57:58 (1763333878) [ 1830.801655] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1830.909345] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1830.999925] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.062189] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.152950] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.222545] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.294112] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.408801] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.478252] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.571970] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.692944] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.772974] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.875363] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1831.946606] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.027488] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.105256] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.180286] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.253921] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.317317] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.393046] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.484678] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.570881] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.650914] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.742421] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.808726] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.879970] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1832.949435] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.030934] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.094439] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.163548] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.227825] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.281879] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.323150] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.384655] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.470360] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.529925] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.631119] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.719348] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.825518] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.902296] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.938278] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1833.984303] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.060562] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.127860] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.198786] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.298492] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.356837] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.418635] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.470694] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.531651] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.648137] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.762470] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.855571] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1834.916781] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.001045] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.063419] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.146614] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.250402] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.307666] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.358836] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.433610] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.523128] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.600098] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.687436] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.780151] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.894639] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1835.974559] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.067681] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.128305] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.218172] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.376849] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.490253] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.559903] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.649533] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.715308] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.787990] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.896518] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1836.992547] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.066424] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.125315] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.182112] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.248933] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.357566] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.441516] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.517839] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.585776] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.652596] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.705873] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.770692] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.870153] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.924686] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1837.992774] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.047644] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.144781] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.233540] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.293498] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.430908] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.558497] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.624455] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.715220] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.811980] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.873693] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1838.959126] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.046063] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.145858] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.208511] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.294797] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.390730] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.483688] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.551164] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.652503] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.748945] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.849302] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1839.912458] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.016922] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.138709] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.209264] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.331367] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.451535] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.536843] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.625844] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.724868] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.797234] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.857617] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1840.926642] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.048669] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.193645] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.310145] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.402476] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.479831] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.568718] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.678173] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.754165] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.815240] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.880967] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1841.958783] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.057723] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.139994] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.211703] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.270506] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.326654] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.379752] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.455875] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.527578] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.588759] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.651540] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.750501] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.805981] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.883511] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1842.932654] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.004436] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.057562] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.089388] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.193109] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.300321] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.366326] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.411444] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.461865] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.526597] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.588388] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.684862] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.747610] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.809621] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.851833] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1843.939693] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.040172] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.185548] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.254975] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.342556] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.447171] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.534312] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.650106] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.742415] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.809881] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.911088] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1844.999539] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.088469] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.207316] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.294478] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.367533] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.458836] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.539879] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.682133] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.798625] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.873201] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.932488] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1845.983503] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.064458] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.134735] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.194561] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.266145] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.338176] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.413450] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.498272] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.596079] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.667529] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.736915] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.844127] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1846.945239] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.023303] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.076412] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.137283] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.229327] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.263865] Lustre: lustre-OST0000-osc-ffff9c0450982800: disconnect after 22s idle [ 1847.312624] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.385519] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.498902] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.562388] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.617155] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.637082] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.681374] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.735590] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.826720] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.905643] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1847.998253] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.078097] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.156552] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.272380] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.359606] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.451275] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.518863] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.605476] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.678923] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.780791] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.844938] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1848.948531] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.019547] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.110456] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.176669] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.261997] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.334821] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.414076] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.465538] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.526528] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.579345] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.636436] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.683269] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.757790] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.816265] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.877356] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.918208] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1849.998683] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1850.060422] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1850.102438] rw_seq_cst_vs_d (32452): drop_caches: 3 [ 1852.385710] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 24s idle [ 1855.991903] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 17:58:24 (1763333904) [ 1856.345066] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.391244] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.502320] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.607188] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.712964] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.734869] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.864665] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1856.957640] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.048724] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.086863] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.163833] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.272499] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.341150] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.449230] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.492209] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.537552] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.585815] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.615559] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.874246] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1857.968283] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.031763] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.076591] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.135967] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.159947] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.472392] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.568772] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.628927] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.654132] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.769517] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.805418] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.837769] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1858.945989] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.022421] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.060888] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.143552] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.294641] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.406654] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.467069] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.528571] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.555080] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.662036] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.742560] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1859.967437] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.025637] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.251700] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.278401] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.309157] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.406927] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.545951] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.687509] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.790284] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1860.902400] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.092347] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.156573] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.198283] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.242192] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.340339] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.446133] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.554518] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.601770] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.628035] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.779233] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1861.900531] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.056678] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.138758] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.235548] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.275486] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.412394] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.506265] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.588561] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.707767] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.749209] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.785133] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.867614] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.915111] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1862.989588] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.036755] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.173466] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.331799] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.409569] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.449039] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.598297] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.766086] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.855518] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.894682] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1863.952188] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.032129] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.184464] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.297292] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.468986] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.582750] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.757312] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.827278] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.927929] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1864.983376] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.045629] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.254525] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.404274] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.454866] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.570614] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.801478] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.871188] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.919409] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1865.999431] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.138029] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.273325] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.458761] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.530382] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.615474] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.779995] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.820898] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.847500] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1866.968447] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.068412] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.119496] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.223313] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.308690] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.430471] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.506759] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.670883] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.735257] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.871864] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1867.904526] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.077109] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.113995] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.190732] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.383837] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.501807] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.540592] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.656471] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.705325] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.736218] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.813162] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.886281] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1868.989410] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.119854] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.258581] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.302886] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.336618] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.463481] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.524166] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.646352] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.712238] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.816397] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.898901] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.929571] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1869.997189] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.168310] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.337363] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.386523] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.426781] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.453318] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.571564] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.613153] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.756905] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.847193] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1870.966653] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.073591] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.208312] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.267688] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.288348] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.392472] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.448976] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.508690] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.573652] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.630635] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.665359] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.868629] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1871.940344] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.034056] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.159631] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.258301] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.304447] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.418174] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.486884] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.555430] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.716362] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.821134] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.867080] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1872.867397] Lustre: lustre-OST0001-osc-ffff9c0450982800: disconnect after 23s idle [ 1872.943614] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.124570] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.217730] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.316183] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.362572] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.453418] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.577137] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.748671] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.871775] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.918947] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1873.958697] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.069627] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.154510] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.262821] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.372603] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.468441] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.591425] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.687424] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.725766] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.752894] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.843933] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.887349] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.970316] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1874.999843] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.164202] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.238567] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.311170] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.406702] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.469206] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.530313] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.677265] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.770148] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1875.912941] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1876.035624] rw_seq_cst_vs_d (33044): drop_caches: 3 [ 1882.203130] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 17:58:50 (1763333930) [ 1888.621727] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 17:58:57 (1763333937) [ 1896.293098] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 17:59:04 (1763333944) [ 1922.881844] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 17:59:31 (1763333971) [ 1923.292890] bash (35534): drop_caches: 3 [ 1926.622687] bash (35534): drop_caches: 3 [ 1929.719841] bash (35534): drop_caches: 3 [ 1932.876202] bash (35534): drop_caches: 3 [ 1936.020341] bash (35534): drop_caches: 3 [ 1939.181884] bash (35534): drop_caches: 3 [ 1942.421618] bash (35534): drop_caches: 3 [ 1945.607969] bash (35534): drop_caches: 3 [ 1948.923242] bash (35534): drop_caches: 3 [ 1985.533973] bash (35534): drop_caches: 3 [ 1988.625675] bash (35534): drop_caches: 3 [ 1993.377412] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 18:00:41 (1763334041) [ 2002.177947] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 18:00:50 (1763334050) [ 2016.224228] Lustre: lustre-OST0001-osc-ffff9c0450982800: disconnect after 22s idle [ 2016.227108] Lustre: Skipped 1 previous similar message [ 2042.058585] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 18:01:30 (1763334090) [ 2051.482912] Lustre: DEBUG MARKER: loop 5 [ 2056.308845] Lustre: DEBUG MARKER: loop 10 [ 2057.184167] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 24s idle [ 2061.662802] Lustre: DEBUG MARKER: loop 15 [ 2065.897480] Lustre: DEBUG MARKER: loop 20 [ 2072.519453] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 18:02:01 (1763334121) [ 2078.263891] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 18:02:06 (1763334126) [ 2084.326573] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 18:02:12 (1763334132) [ 2152.403607] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 18:03:20 (1763334200) [ 2158.629311] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 18:03:26 (1763334206) [ 2164.121645] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 18:03:32 (1763334212) [ 2171.452744] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 18:03:39 (1763334219) [ 2177.739672] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 18:03:46 (1763334226) [ 2183.726216] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 18:03:52 (1763334232) [ 2190.599650] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 18:03:59 (1763334239) [ 2197.082862] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2198.399768] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 18:04:06 (1763334246) [ 2206.312652] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 18:04:14 (1763334254) [ 2206.780741] Lustre: *** cfs_fail_loc=314, val=0*** [ 2207.839656] Lustre: *** cfs_fail_loc=314, val=0*** [ 2207.841462] Lustre: Skipped 2 previous similar messages [ 2214.014937] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 18:04:22 (1763334262) [ 2223.636149] Lustre: *** cfs_fail_loc=314, val=0*** [ 2223.743242] LustreError: lustre-OST0000-osc-ffff9c0450982800: operation ldlm_enqueue to node 192.168.202.102@tcp failed: rc = -107 [ 2223.751088] Lustre: lustre-OST0000-osc-ffff9c0450982800: Connection to lustre-OST0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2223.763910] LustreError: lustre-OST0000-osc-ffff9c0450982800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2223.772766] LustreError: 46462:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9c0450982800: namespace resource [0x280000400:0x9:0x0].0x0 (ffff9c0458542a00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2223.787285] Lustre: lustre-OST0000-osc-ffff9c0450982800: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [ 2231.403842] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 18:04:39 (1763334279) [ 2231.883088] LustreError: 47053:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2234.911328] LustreError: 47053:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2242.400374] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 18:04:49 (1763334289) [ 2250.916218] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 18:04:58 (1763334298) [ 2258.654622] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2260.669088] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 18:05:08 (1763334308) [ 2262.432678] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2264.049928] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 18:05:12 (1763334312) [ 2267.106983] Lustre: lustre-MDT0000-mdc-ffff9c0459e1a000: Connection to lustre-MDT0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2277.368709] LustreError: MGC192.168.202.102@tcp: Connection to MGS (at 192.168.202.102@tcp) was lost; in progress operations using this service will fail [ 2277.386224] Lustre: Evicted from MGS (at 192.168.202.102@tcp) after server handle changed from 0x70df4ec4a1bbd995 to 0x70df4ec4a1c7d36b [ 2277.405382] Lustre: MGC192.168.202.102@tcp: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [ 2279.851558] Lustre: lustre-MDT0000-mdc-ffff9c0459e1a000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [ 2306.280974] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 18:05:54 (1763334354) [ 2347.969738] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 18:06:36 (1763334396) [ 2370.084490] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 18:06:57 (1763334417) [ 2425.831520] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: Connection to lustre-OST0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2425.846250] Lustre: Skipped 1 previous similar message [ 2425.854724] LustreError: lustre-OST0000-osc-ffff9c0459e1a000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2425.862937] LustreError: lustre-OST0000-osc-ffff9c0450982800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2425.864227] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [ 2425.881966] Lustre: Skipped 2 previous similar messages [ 2435.020560] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: Connection to lustre-OST0001 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2435.042057] Lustre: Skipped 1 previous similar message [ 2435.061635] LustreError: lustre-OST0001-osc-ffff9c0459e1a000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2435.087117] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [ 2446.304427] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 20s idle [ 2446.307348] Lustre: Skipped 4 previous similar messages [ 2453.170182] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid 50 [ 2454.327304] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid in IDLE state after 0 sec [ 2457.698638] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid 50 [ 2459.107108] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid in FULL state after 0 sec [ 2463.592869] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid 50 [ 2465.081572] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid in IDLE state after 0 sec [ 2468.607305] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid 50 [ 2470.177251] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid in FULL state after 0 sec [ 2480.779234] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid 50 [ 2482.345244] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c0450982800.ost_server_uuid in IDLE state after 0 sec [ 2485.902624] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid 50 [ 2487.430985] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c0450982800.ost_server_uuid in FULL state after 0 sec [ 2489.123324] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 18:08:57 (1763334537) [ 2491.217728] Lustre: DEBUG MARKER: Race attempt 0 [ 2493.923417] Lustre: DEBUG MARKER: Wait for 57518 57594 for 60 sec... [ 2559.483175] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 18:10:07 (1763334607) [ 2566.915390] Lustre: DEBUG MARKER: start test - cycle (0) [ 2584.311923] Lustre: DEBUG MARKER: start test - cycle (1) [ 2605.639286] Lustre: DEBUG MARKER: start test - cycle (2) [ 2628.258757] Lustre: DEBUG MARKER: start test - cycle (3) [ 2650.644388] Lustre: DEBUG MARKER: start test - cycle (4) [ 2656.224240] Lustre: lustre-OST0000-osc-ffff9c0450982800: disconnect after 21s idle [ 2656.226842] Lustre: Skipped 2 previous similar messages [ 2668.392553] Lustre: DEBUG MARKER: start test - cycle (5) [ 2687.837107] Lustre: DEBUG MARKER: start test - cycle (6) [ 2706.748901] Lustre: DEBUG MARKER: start test - cycle (7) [ 2728.193286] Lustre: DEBUG MARKER: start test - cycle (8) [ 2747.636280] Lustre: DEBUG MARKER: start test - cycle (9) [ 2766.385444] Lustre: DEBUG MARKER: start test - cycle (10) [ 2792.413042] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 18:14:00 (1763334840) [ 2862.334208] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 18:15:10 (1763334910) [ 2868.689099] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 18:15:17 (1763334917) [ 2875.522878] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 18:15:23 (1763334923) [ 2881.385835] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 18:15:30 (1763334930) [ 2881.602797] Lustre: *** cfs_fail_loc=411, val=0*** [ 2886.198792] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2887.775337] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 18:15:36 (1763334936) [ 2900.746985] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 18:15:49 (1763334949) [ 2917.042801] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 18:16:04 (1763334964) [ 2917.346722] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: disconnect after 20s idle [ 2917.352901] Lustre: Skipped 5 previous similar messages [ 2934.132139] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 18:16:22 (1763334982) [ 2948.067195] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 18:16:36 (1763334996) [ 2957.714182] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 18:16:46 (1763335006) [ 2968.368728] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 18:16:56 (1763335016) [ 2979.047910] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 18:17:07 (1763335027) [ 2991.471690] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 18:17:19 (1763335039) [ 3003.680397] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 18:17:32 (1763335052) [ 3016.230546] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 18:17:44 (1763335064) [ 3028.289873] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 18:17:56 (1763335076) [ 3039.860553] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 18:18:08 (1763335088) [ 3659.743323] Lustre: lustre-OST0000-osc-ffff9c0450982800: disconnect after 24s idle [ 3659.751864] Lustre: Skipped 5 previous similar messages [ 3990.759935] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:33:59 (1763336039) [ 4000.865941] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:34:09 (1763336049) [ 4012.135738] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:34:20 (1763336060) [ 4023.697989] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:34:31 (1763336071) [ 4035.694484] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:34:43 (1763336083) [ 4046.748851] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:34:54 (1763336094) [ 4057.640252] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:35:05 (1763336105) [ 4068.676200] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:35:17 (1763336117) [ 4079.986600] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:35:28 (1763336128) [ 4182.911454] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:37:11 (1763336231) [ 4195.550280] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:37:23 (1763336243) [ 4208.749224] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:37:37 (1763336257) [ 4220.813276] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:37:48 (1763336268) [ 4234.045252] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 18:38:02 (1763336282) [ 4244.592641] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 18:38:13 (1763336293) [ 4255.421608] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 18:38:23 (1763336303) [ 4267.204862] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 18:38:35 (1763336315) [ 4278.748408] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 18:38:46 (1763336326) [ 4284.383224] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 20s idle [ 4284.388079] Lustre: Skipped 4 previous similar messages [ 4384.676482] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 18:40:32 (1763336432) [ 5403.252757] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 18:57:31 (1763337451) [ 5405.663271] Lustre: lustre-OST0000-osc-ffff9c0450982800: disconnect after 20s idle [ 5405.671187] Lustre: Skipped 10 previous similar messages [ 5416.816982] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 18:57:45 (1763337465) [ 5430.418549] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 18:57:58 (1763337478) [ 5443.361403] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 18:58:11 (1763337491) [ 5455.611270] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 18:58:23 (1763337503) [ 5467.300110] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 18:58:35 (1763337515) [ 5479.702097] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 18:58:47 (1763337527) [ 5491.573437] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 18:58:59 (1763337539) [ 5505.847181] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 18:59:13 (1763337553) [ 5518.141192] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 18:59:26 (1763337566) [ 5690.783871] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:02:18 (1763337738) [ 5703.245216] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:02:31 (1763337751) [ 5715.316924] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:02:43 (1763337763) [ 5726.843800] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:02:55 (1763337775) [ 5738.193920] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:03:06 (1763337786) [ 5750.501197] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 19:03:18 (1763337798) [ 5761.672250] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 19:03:30 (1763337810) [ 5772.818675] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 19:03:41 (1763337821) [ 5784.142148] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 19:03:52 (1763337832) [ 6071.265821] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 20s idle [ 6071.270417] Lustre: Skipped 11 previous similar messages [ 6839.266319] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 23s idle [ 6839.271854] Lustre: Skipped 3 previous similar messages [ 6878.013703] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 19:22:06 (1763338926) [ 6890.084827] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 19:22:18 (1763338938) [ 6903.108121] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 19:22:31 (1763338951) [ 6916.102941] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 19:22:44 (1763338964) [ 6929.337312] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 19:22:57 (1763338977) [ 6943.292876] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 19:23:11 (1763338991) [ 6957.256427] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 19:23:25 (1763339005) [ 6972.551551] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 19:23:40 (1763339020) [ 6985.817074] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 19:23:54 (1763339034) [ 6997.045762] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 19:24:05 (1763339045) [ 7008.971675] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 19:24:17 (1763339057) [ 7022.266377] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 19:24:30 (1763339070) [ 7034.265698] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 19:24:42 (1763339082) [ 7045.817301] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 19:24:54 (1763339094) [ 7058.324672] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 19:25:06 (1763339106) [ 7069.385661] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 19:25:17 (1763339117) [ 7082.964880] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 19:25:31 (1763339131) [ 7083.363254] LustreError: 6155:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7085.455178] LustreError: 6155:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7094.510814] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 19:25:42 (1763339142) [ 7101.828288] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 19:25:50 (1763339150) [ 7102.213809] LustreError: 238282:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7106.295173] LustreError: 238282:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7106.336818] LustreError: 238282:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7110.435372] LustreError: 238282:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7110.512868] LustreError: 238289:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7114.591135] LustreError: 238289:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7122.148610] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 19:26:10 (1763339170) [ 7134.828449] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 19:26:22 (1763339182) [ 7143.061438] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 19:26:31 (1763339191) [ 7152.202891] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 19:26:40 (1763339200) [ 7185.134577] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 19:27:13 (1763339233) [ 7198.116198] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 19:27:26 (1763339246) [ 7210.225991] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 19:27:38 (1763339258) [ 7228.266884] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 19:27:56 (1763339276) [ 7243.775857] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 19:28:11 (1763339291) [ 7262.863402] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 19:28:31 (1763339311) [ 7283.624217] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 19:28:51 (1763339331) [ 7306.412694] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 19:29:14 (1763339354) [ 7327.470091] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 19:29:35 (1763339375) [ 7420.964483] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 19:31:08 (1763339468) [ 7430.590992] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7438.108331] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 19:31:26 (1763339486) [ 7447.348255] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 19:31:35 (1763339495) [ 7454.347144] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 19:31:42 (1763339502) [ 7460.865732] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 19:31:48 (1763339508) [ 7469.025736] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: disconnect after 24s idle [ 7469.028245] Lustre: Skipped 10 previous similar messages [ 7503.017855] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 19:32:31 (1763339551) [ 7545.959189] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 19:33:13 (1763339593) [ 7552.951882] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 19:33:20 (1763339600) [ 7560.132571] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 19:33:28 (1763339608) [ 7563.698105] LustreError: lustre-MDT0000-mdc-ffff9c0459e1a000: operation ldlm_enqueue to node 192.168.202.102@tcp failed: rc = -35 [ 7571.719286] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 19:33:39 (1763339619) [ 7572.196860] LustreError: 2407:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7574.287177] LustreError: 2407:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7583.680876] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 19:33:51 (1763339631) [ 7753.453220] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 19:36:41 (1763339801) [ 7764.922619] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 19:36:52 (1763339812) [ 7780.619413] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 19:37:08 (1763339828) [ 7798.592727] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 19:37:26 (1763339846) [ 7814.867167] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 19:37:42 (1763339862) [ 7839.895203] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 19:38:07 (1763339887) [ 7865.806484] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 19:38:33 (1763339913) [ 7880.964645] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 19:38:47 (1763339927) [ 7896.194340] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 19:39:04 (1763339944) [ 7921.835452] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 19:39:29 (1763339969) [ 7975.669491] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 19:40:23 (1763340023) [ 8103.903801] Lustre: lustre-OST0000-osc-ffff9c0459e1a000: disconnect after 21s idle [ 8103.906505] Lustre: Skipped 11 previous similar messages [ 8110.802350] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 19:42:38 (1763340158) [ 8246.973964] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 19:44:55 (1763340295) [ 8583.360389] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 19:50:31 (1763340631) [ 8620.993977] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 19:51:09 (1763340669) [ 8658.376354] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 19:51:46 (1763340706) [ 8693.161342] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 19:52:21 (1763340741) [ 8723.427384] Lustre: lustre-OST0001-osc-ffff9c0459e1a000: disconnect after 24s idle [ 8723.430143] Lustre: Skipped 15 previous similar messages [ 8765.508292] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 19:53:33 (1763340813) [ 8833.688287] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 19:54:41 (1763340881) [ 8950.869521] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 19:56:39 (1763340999) [ 8954.155788] LustreError: 283462:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0459e1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8954.167336] LustreError: 283462:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8954.211285] Lustre: Unmounted lustre-client [ 8956.438993] LustreError: 283475:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450982800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8956.445486] LustreError: 283475:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8956.452016] LustreError: 283475:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8956.460557] LustreError: 283475:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8956.508121] Lustre: Unmounted lustre-client [ 9065.784692] Lustre: Mounted lustre-client [ 9068.228873] Lustre: Mounted lustre-client [ 9071.044463] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9159.164763] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 20:00:07 (1763341207) [ 9176.173996] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 20:00:24 (1763341224) [ 9185.663643] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 20:00:33 (1763341233) [ 9242.417982] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 20:01:30 (1763341290) [ 9301.728372] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 20:02:30 (1763341350) [ 9312.260602] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 20:02:40 (1763341360) [ 9419.339686] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 20:04:27 (1763341467) [ 9451.317421] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 20:04:59 (1763341499) [ 9499.214145] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 20:05:47 (1763341547) [ 9506.095121] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 20:05:54 (1763341554) [ 9522.704557] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 20:06:11 (1763341571) [ 9524.191269] Lustre: lustre-OST0000-osc-ffff9c046f9c1000: disconnect after 22s idle [ 9524.201596] Lustre: Skipped 11 previous similar messages [ 9531.428086] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 20:06:19 (1763341579) [ 9532.714757] LustreError: 300904:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1be:0x0]: rc = -5 [ 9532.724467] LustreError: 300904:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9533.466149] LustreError: 300913:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x23:0x0]: rc = -5 [ 9533.471595] LustreError: 300913:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9534.470307] LustreError: 300934:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x23:0x0]: rc = -5 [ 9534.477778] LustreError: 300934:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 4 previous similar messages [ 9534.483407] LustreError: 300934:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9534.488949] LustreError: 300934:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 4 previous similar messages [ 9536.844568] LustreError: 300974:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1cb:0x0]: rc = -5 [ 9536.856295] LustreError: 300974:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 9536.863309] LustreError: 300974:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9536.872645] LustreError: 300974:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 11 previous similar messages [ 9541.010649] LustreError: 301039:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x44:0x0]: rc = -5 [ 9541.015574] LustreError: 301039:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 17 previous similar messages [ 9541.018742] LustreError: 301039:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9541.021535] LustreError: 301039:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 17 previous similar messages [ 9549.014386] LustreError: 301181:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1fe:0x0]: rc = -5 [ 9549.024691] LustreError: 301181:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 42 previous similar messages [ 9549.039139] LustreError: 301181:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9549.042242] LustreError: 301181:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 42 previous similar messages [ 9565.253895] LustreError: 301472:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x24b:0x0]: rc = -5 [ 9565.260559] LustreError: 301472:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 85 previous similar messages [ 9565.268437] LustreError: 301472:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9565.278464] LustreError: 301472:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 85 previous similar messages [ 9597.579701] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 20:07:25 (1763341645) [ 9604.635144] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 20:07:32 (1763341652) [ 9822.304392] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 20:11:10 (1763341870) [ 9823.569621] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9824.995020] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 20:11:13 (1763341873) [10057.209608] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 20:15:05 (1763342105) [10064.039433] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 20:15:12 (1763342112) [10158.055976] Lustre: lustre-OST0001-osc-ffff9c046f9c1000: disconnect after 20s idle [10158.069941] Lustre: Skipped 4 previous similar messages [10191.347626] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 20:17:19 (1763342239) [10203.658718] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 20:17:31 (1763342251) [10213.315642] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 20:17:41 (1763342261) [10399.795910] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 20:20:48 (1763342448) [10587.531927] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 20:23:55 (1763342635) [10594.647669] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 20:24:02 (1763342642) [10611.425685] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 20:24:19 (1763342659) [10611.766812] Lustre: DEBUG MARKER: write [10611.819839] LustreError: 285887:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10613.869726] Lustre: DEBUG MARKER: kill 331645 [10613.882405] LustreError: 331645:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10616.839179] LustreError: 285887:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10619.951180] LustreError: 331645:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10626.206172] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 20:24:34 (1763342674) [10629.037730] LustreError: 332263:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10639.103169] LustreError: 332263:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [10646.550191] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:24:54 (1763342694) [10647.049933] LustreError: 332852:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10649.136676] LustreError: 332852:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [10660.399320] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:25:08 (1763342708) [10662.258335] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10663.681540] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:25:12 (1763342712) [10672.237759] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:25:20 (1763342720) [10680.017604] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:25:28 (1763342728) [10687.562604] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:25:35 (1763342735) [10695.335704] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:25:43 (1763342743) [10702.082263] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:25:50 (1763342750) [10709.278414] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:25:57 (1763342757) [10717.455985] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:26:05 (1763342765) [10726.945090] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:26:15 (1763342775) [10728.594705] Lustre: *** cfs_fail_loc=415, val=0*** [10740.380906] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:26:28 (1763342788) [10773.170900] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:27:00 (1763342820) [10773.644711] LustreError: 285194:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [10773.665594] LustreError: 285194:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [10778.655138] LustreError: 285194:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10788.687173] LustreError: 285193:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10788.694154] LustreError: 285193:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [10801.137341] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:27:29 (1763342849) [10808.955662] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:27:36 (1763342856) [10816.674918] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:27:44 (1763342864) [10823.859632] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:27:52 (1763342872) [10833.561388] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:28:01 (1763342881) [10847.843845] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:28:16 (1763342896) [10848.580548] LustreError: 343603:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [10848.584529] LustreError: 343603:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [10852.615137] LustreError: 343603:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [10852.618566] LustreError: 343603:0:(osc_request.c:2944:osc_build_rpc()) Skipped 3 previous similar messages [10859.859945] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:28:27 (1763342907) [10863.750614] LustreError: 344314:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c046f9c1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10863.762250] LustreError: 344314:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10863.778629] LustreError: 344314:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10863.782212] LustreError: 344314:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10863.844339] Lustre: Unmounted lustre-client [10865.619268] LustreError: 344334:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0448cbe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10865.622602] LustreError: 344334:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10865.638072] LustreError: 344334:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10865.640772] LustreError: 344334:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10865.748252] Lustre: Unmounted lustre-client [10867.257167] Lustre: DEBUG MARKER: Iteration 1 [10867.668964] LustreError: 344497:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10867.669195] LustreError: 344498:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10867.695216] LustreError: 344497:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [10867.990410] Lustre: Mounted lustre-client [10869.311970] LustreError: 344612:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0458910800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10869.324534] LustreError: 344612:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [10869.346638] LustreError: 344611:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10869.360518] LustreError: 344611:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10869.490709] Lustre: Unmounted lustre-client [10872.787922] Key type lgssc unregistered [10873.104855] LNet: 344855:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10873.123663] LNetError: 344855:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10873.157525] LNet: Removed LNI 192.168.202.2@tcp [10874.101326] Key type .llcrypt unregistered [10874.104174] Key type ._llcrypt unregistered [10875.173713] Key type ._llcrypt registered [10875.176692] Key type .llcrypt registered [10875.784396] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10875.819462] alg: No test for adler32 (adler32-zlib) [10877.415392] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [10878.396091] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [10880.375168] Key type lgssc registered [10882.186101] Lustre: Echo OBD driver; http://www.lustre.org/ [10895.637954] Lustre: DEBUG MARKER: Iteration 2 [10896.062116] LustreError: 345686:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10896.064776] LustreError: 345685:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10896.069080] LustreError: 345686:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10897.523414] Lustre: Mounted lustre-client [10899.237807] LustreError: 345794:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047ddc2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10899.256077] LustreError: 345794:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10899.347473] Lustre: Unmounted lustre-client [10902.191658] Key type lgssc unregistered [10902.480607] LNet: 346043:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10902.497616] LNetError: 346043:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10902.522249] LNet: Removed LNI 192.168.202.2@tcp [10903.239284] Key type .llcrypt unregistered [10903.244933] Key type ._llcrypt unregistered [10904.100869] Key type ._llcrypt registered [10904.102336] Key type .llcrypt registered [10904.726974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10904.742910] alg: No test for adler32 (adler32-zlib) [10905.974301] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [10906.191767] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [10907.887172] Key type lgssc registered [10909.146063] Lustre: Echo OBD driver; http://www.lustre.org/ [10920.711864] Lustre: DEBUG MARKER: Iteration 3 [10921.106306] LustreError: 346873:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10921.111432] LustreError: 346877:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10921.128932] LustreError: 346873:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [10922.545253] Lustre: Mounted lustre-client [10924.185948] LustreError: 346982:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450b1e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10924.217367] LustreError: 346982:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10924.287330] Lustre: Unmounted lustre-client [10926.979544] Key type lgssc unregistered [10927.259753] LNet: 347232:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10927.275267] LNetError: 347232:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10927.301315] LNet: Removed LNI 192.168.202.2@tcp [10928.058347] Key type .llcrypt unregistered [10928.063551] Key type ._llcrypt unregistered [10928.736045] Key type ._llcrypt registered [10928.746843] Key type .llcrypt registered [10929.181916] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10929.202953] alg: No test for adler32 (adler32-zlib) [10930.465727] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [10930.701397] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [10932.359228] Key type lgssc registered [10934.009263] Lustre: Echo OBD driver; http://www.lustre.org/ [10948.769655] Lustre: DEBUG MARKER: Iteration 4 [10949.113168] LustreError: 348064:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10949.118808] LustreError: 348065:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10949.138715] LustreError: 348064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [10950.580183] Lustre: Mounted lustre-client [10951.505909] LustreError: 348171:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450445800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10951.520036] LustreError: 348171:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10951.581275] Lustre: Unmounted lustre-client [10954.350670] Key type lgssc unregistered [10954.662979] LNet: 348421:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10954.683885] LNetError: 348421:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10954.704111] LNet: Removed LNI 192.168.202.2@tcp [10955.341525] Key type .llcrypt unregistered [10955.344376] Key type ._llcrypt unregistered [10956.126784] Key type ._llcrypt registered [10956.128469] Key type .llcrypt registered [10956.677029] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10956.685320] alg: No test for adler32 (adler32-zlib) [10957.746734] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [10957.975080] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [10959.687187] Key type lgssc registered [10960.909480] Lustre: Echo OBD driver; http://www.lustre.org/ [10972.158892] Lustre: DEBUG MARKER: Iteration 5 [10972.534802] LustreError: 349251:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10972.538096] LustreError: 349252:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10972.548572] LustreError: 349251:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [10973.923211] Lustre: Mounted lustre-client [10975.312061] LustreError: 349361:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c046f9c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10975.349493] LustreError: 349361:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10975.416462] Lustre: Unmounted lustre-client [10978.278945] Key type lgssc unregistered [10978.546395] LNet: 349609:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10978.555616] LNetError: 349609:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10978.575249] LNet: Removed LNI 192.168.202.2@tcp [10979.291292] Key type .llcrypt unregistered [10979.295395] Key type ._llcrypt unregistered [10979.913758] Key type ._llcrypt registered [10979.915317] Key type .llcrypt registered [10980.421262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10980.452890] alg: No test for adler32 (adler32-zlib) [10981.537506] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [10981.772626] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [10983.495711] Key type lgssc registered [10984.672954] Lustre: Echo OBD driver; http://www.lustre.org/ [10995.673919] Lustre: DEBUG MARKER: Iteration 6 [10996.027046] LustreError: 350439:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10996.030235] LustreError: 350441:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10996.039162] LustreError: 350439:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10997.409490] Lustre: Mounted lustre-client [10998.766700] LustreError: 350546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047198e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10998.805565] LustreError: 350546:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10998.929965] Lustre: Unmounted lustre-client [11001.499869] Key type lgssc unregistered [11001.729495] LNet: 350795:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11001.745159] LNetError: 350795:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11001.762652] LNet: Removed LNI 192.168.202.2@tcp [11002.484298] Key type .llcrypt unregistered [11002.486180] Key type ._llcrypt unregistered [11003.234793] Key type ._llcrypt registered [11003.236060] Key type .llcrypt registered [11003.957563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11003.974420] alg: No test for adler32 (adler32-zlib) [11005.144302] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11005.410469] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11007.127173] Key type lgssc registered [11008.741402] Lustre: Echo OBD driver; http://www.lustre.org/ [11020.136535] Lustre: DEBUG MARKER: Iteration 7 [11020.610025] LustreError: 351623:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11020.610407] LustreError: 351626:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11020.677929] LustreError: 351623:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11022.193742] Lustre: Mounted lustre-client [11024.288461] LustreError: 351738:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0448cb9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11024.326095] LustreError: 351738:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11024.395932] Lustre: Unmounted lustre-client [11027.373121] Key type lgssc unregistered [11027.602790] LNet: 351988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11027.617419] LNetError: 351988:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11028.649135] LNet: Removed LNI 192.168.202.2@tcp [11029.388169] Key type .llcrypt unregistered [11029.394300] Key type ._llcrypt unregistered [11029.986092] Key type ._llcrypt registered [11029.999927] Key type .llcrypt registered [11030.584976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11030.594457] alg: No test for adler32 (adler32-zlib) [11031.726501] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11031.927064] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11033.583665] Key type lgssc registered [11034.871462] Lustre: Echo OBD driver; http://www.lustre.org/ [11045.934467] Lustre: DEBUG MARKER: Iteration 8 [11046.253862] LustreError: 352818:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11046.259441] LustreError: 352824:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11046.273950] LustreError: 352818:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [11047.582494] Lustre: Mounted lustre-client [11048.743799] LustreError: 352930:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c044a5a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11048.779794] LustreError: 352930:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11048.847132] Lustre: Unmounted lustre-client [11051.179730] Key type lgssc unregistered [11051.399877] LNet: 353176:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11051.408371] LNetError: 353176:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11051.421381] LNet: Removed LNI 192.168.202.2@tcp [11051.939796] Key type .llcrypt unregistered [11051.941601] Key type ._llcrypt unregistered [11052.387840] Key type ._llcrypt registered [11052.402458] Key type .llcrypt registered [11052.750077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11052.768382] alg: No test for adler32 (adler32-zlib) [11053.787540] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11053.998969] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11055.671164] Key type lgssc registered [11056.846010] Lustre: Echo OBD driver; http://www.lustre.org/ [11068.212570] Lustre: DEBUG MARKER: Iteration 9 [11068.835100] LustreError: 354008:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11068.840889] LustreError: 354009:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11068.845657] LustreError: 354008:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11070.134589] Lustre: Mounted lustre-client [11071.520111] LustreError: 354127:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0448dbe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11071.546710] LustreError: 354127:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11071.619973] Lustre: Unmounted lustre-client [11074.356743] Key type lgssc unregistered [11074.611583] LNet: 354370:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11074.623405] LNetError: 354370:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11074.655428] LNet: Removed LNI 192.168.202.2@tcp [11075.217659] Key type .llcrypt unregistered [11075.219136] Key type ._llcrypt unregistered [11075.705632] Key type ._llcrypt registered [11075.708506] Key type .llcrypt registered [11076.237160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11076.262301] alg: No test for adler32 (adler32-zlib) [11077.353514] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11077.562708] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11079.263339] Key type lgssc registered [11080.321888] Lustre: Echo OBD driver; http://www.lustre.org/ [11091.038730] Lustre: DEBUG MARKER: Iteration 10 [11091.362305] LustreError: 355196:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11091.362728] LustreError: 355197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11091.381584] LustreError: 355196:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11092.776318] Lustre: Mounted lustre-client [11094.072497] LustreError: 355313:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04480fa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11094.099507] LustreError: 355313:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11094.221097] Lustre: Unmounted lustre-client [11096.815542] Key type lgssc unregistered [11097.059619] LNet: 355556:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11097.071248] LNetError: 355556:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11097.084465] LNet: Removed LNI 192.168.202.2@tcp [11097.811168] Key type .llcrypt unregistered [11097.812625] Key type ._llcrypt unregistered [11098.692633] Key type ._llcrypt registered [11098.693901] Key type .llcrypt registered [11099.056774] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11099.072290] alg: No test for adler32 (adler32-zlib) [11100.188622] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11100.438787] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11102.103980] Key type lgssc registered [11103.225276] Lustre: Echo OBD driver; http://www.lustre.org/ [11114.335939] Lustre: DEBUG MARKER: Iteration 11 [11114.691566] LustreError: 356386:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11114.691614] LustreError: 356387:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11114.701632] LustreError: 356386:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11116.078795] Lustre: Mounted lustre-client [11117.392105] LustreError: 356501:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0443acd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11117.413478] LustreError: 356504:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11117.417861] LustreError: 356504:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11117.479079] Lustre: Unmounted lustre-client [11119.888943] Key type lgssc unregistered [11120.122302] LNet: 356746:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11120.129524] LNetError: 356746:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11120.143719] LNet: Removed LNI 192.168.202.2@tcp [11120.690584] Key type .llcrypt unregistered [11120.695485] Key type ._llcrypt unregistered [11121.318918] Key type ._llcrypt registered [11121.320492] Key type .llcrypt registered [11121.847807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11121.859075] alg: No test for adler32 (adler32-zlib) [11122.971170] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11123.161377] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11124.882274] Key type lgssc registered [11126.177724] Lustre: Echo OBD driver; http://www.lustre.org/ [11137.522710] Lustre: DEBUG MARKER: Iteration 12 [11137.851502] LustreError: 357571:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11137.868436] LustreError: 357587:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11137.876950] LustreError: 357571:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4977 [11139.180313] Lustre: Mounted lustre-client [11140.615421] LustreError: 357680:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450f4d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11140.631783] LustreError: 357680:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11140.707139] Lustre: Unmounted lustre-client [11143.250784] Key type lgssc unregistered [11143.579721] LNet: 357929:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11143.586975] LNetError: 357929:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11143.610514] LNet: Removed LNI 192.168.202.2@tcp [11144.411164] Key type .llcrypt unregistered [11144.412905] Key type ._llcrypt unregistered [11144.998134] Key type ._llcrypt registered [11144.999880] Key type .llcrypt registered [11145.476228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11145.493212] alg: No test for adler32 (adler32-zlib) [11146.600916] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11146.777226] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11148.471186] Key type lgssc registered [11149.700129] Lustre: Echo OBD driver; http://www.lustre.org/ [11160.865169] Lustre: DEBUG MARKER: Iteration 13 [11161.235039] LustreError: 358762:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11161.237802] LustreError: 358760:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11161.246904] LustreError: 358762:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11162.578876] Lustre: Mounted lustre-client [11163.815459] LustreError: 358872:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0446dcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11163.844102] LustreError: 358872:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11163.958154] Lustre: Unmounted lustre-client [11166.522243] Key type lgssc unregistered [11166.769707] LNet: 359121:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11166.773637] LNetError: 359121:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11166.797440] LNet: Removed LNI 192.168.202.2@tcp [11167.514309] Key type .llcrypt unregistered [11167.516171] Key type ._llcrypt unregistered [11168.294034] Key type ._llcrypt registered [11168.315838] Key type .llcrypt registered [11168.604637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11168.619467] alg: No test for adler32 (adler32-zlib) [11169.701498] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11169.976597] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11171.663180] Key type lgssc registered [11172.861449] Lustre: Echo OBD driver; http://www.lustre.org/ [11184.616903] Lustre: DEBUG MARKER: Iteration 14 [11185.162315] LustreError: 359952:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11185.165603] LustreError: 359951:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11185.184962] LustreError: 359952:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11186.495909] Lustre: Mounted lustre-client [11188.011109] LustreError: 360060:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047dcf6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11188.033483] LustreError: 360060:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11188.129428] Lustre: Unmounted lustre-client [11191.230372] Key type lgssc unregistered [11191.513517] LNet: 360306:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11191.525228] LNetError: 360306:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11191.546027] LNet: Removed LNI 192.168.202.2@tcp [11192.204222] Key type .llcrypt unregistered [11192.205677] Key type ._llcrypt unregistered [11193.189707] Key type ._llcrypt registered [11193.191866] Key type .llcrypt registered [11193.418771] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11193.449262] alg: No test for adler32 (adler32-zlib) [11194.563233] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11194.795520] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11196.479631] Key type lgssc registered [11198.016611] Lustre: Echo OBD driver; http://www.lustre.org/ [11209.808885] Lustre: DEBUG MARKER: Iteration 15 [11210.199736] LustreError: 361134:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11210.200389] LustreError: 361133:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11210.225690] LustreError: 361134:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11211.558659] Lustre: Mounted lustre-client [11212.689440] LustreError: 361252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450444000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11212.702264] LustreError: 361252:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11212.800074] Lustre: Unmounted lustre-client [11212.809526] Lustre: Skipped 1 previous similar message [11215.300843] Key type lgssc unregistered [11215.546710] LNet: 361495:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11215.559531] LNetError: 361495:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11215.593931] LNet: Removed LNI 192.168.202.2@tcp [11216.264182] Key type .llcrypt unregistered [11216.265982] Key type ._llcrypt unregistered [11216.923197] Key type ._llcrypt registered [11216.924825] Key type .llcrypt registered [11217.410521] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11217.424682] alg: No test for adler32 (adler32-zlib) [11218.507853] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11218.718924] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11220.399229] Key type lgssc registered [11221.626446] Lustre: Echo OBD driver; http://www.lustre.org/ [11232.221311] Lustre: DEBUG MARKER: Iteration 16 [11232.586353] LustreError: 362325:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11232.587058] LustreError: 362327:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11232.599310] LustreError: 362325:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11233.850575] Lustre: Mounted lustre-client [11235.127961] LustreError: 362433:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0448cbc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11235.180168] LustreError: 362433:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11235.262402] Lustre: Unmounted lustre-client [11238.005337] Key type lgssc unregistered [11238.246328] LNet: 362683:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11238.255435] LNetError: 362683:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11238.283117] LNet: Removed LNI 192.168.202.2@tcp [11238.764168] Key type .llcrypt unregistered [11238.765775] Key type ._llcrypt unregistered [11239.576880] Key type ._llcrypt registered [11239.580163] Key type .llcrypt registered [11240.019861] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11240.034569] alg: No test for adler32 (adler32-zlib) [11241.224186] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11241.546278] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11243.263928] Key type lgssc registered [11244.529370] Lustre: Echo OBD driver; http://www.lustre.org/ [11255.813367] Lustre: DEBUG MARKER: Iteration 17 [11256.237740] LustreError: 363514:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11256.253683] LustreError: 363523:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11256.263683] LustreError: 363514:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [11257.551855] Lustre: Mounted lustre-client [11258.562438] LustreError: 363622:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047dcf0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11258.584825] LustreError: 363622:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11258.676372] Lustre: Unmounted lustre-client [11258.679106] Lustre: Skipped 1 previous similar message [11261.197413] Key type lgssc unregistered [11261.409698] LNet: 363871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11261.414422] LNetError: 363871:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11261.438332] LNet: Removed LNI 192.168.202.2@tcp [11262.071497] Key type .llcrypt unregistered [11262.073298] Key type ._llcrypt unregistered [11262.556715] Key type ._llcrypt registered [11262.558452] Key type .llcrypt registered [11263.095839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11263.107329] alg: No test for adler32 (adler32-zlib) [11264.293242] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11264.623618] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11266.351186] Key type lgssc registered [11267.500701] Lustre: Echo OBD driver; http://www.lustre.org/ [11279.642845] Lustre: DEBUG MARKER: Iteration 18 [11279.979454] LustreError: 364698:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11279.982375] LustreError: 364700:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11279.997988] LustreError: 364698:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11281.331207] Lustre: Mounted lustre-client [11282.674733] LustreError: 364810:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c046f9c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11282.691966] LustreError: 364810:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11282.729216] Lustre: Unmounted lustre-client [11285.176483] Key type lgssc unregistered [11285.417187] LNet: 365057:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11285.423852] LNetError: 365057:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11285.447239] LNet: Removed LNI 192.168.202.2@tcp [11286.009353] Key type .llcrypt unregistered [11286.017786] Key type ._llcrypt unregistered [11286.755791] Key type ._llcrypt registered [11286.757586] Key type .llcrypt registered [11287.259727] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11287.280607] alg: No test for adler32 (adler32-zlib) [11288.339930] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11288.511355] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11290.193194] Key type lgssc registered [11291.371790] Lustre: Echo OBD driver; http://www.lustre.org/ [11301.621931] Lustre: DEBUG MARKER: Iteration 19 [11302.138906] LustreError: 365890:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11302.138935] LustreError: 365889:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11302.149720] LustreError: 365890:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11304.516233] Lustre: Mounted lustre-client [11305.631832] LustreError: 366005:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0448918800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11305.653137] LustreError: 366008:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11305.654107] LustreError: 366005:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11305.738134] Lustre: Unmounted lustre-client [11308.112901] Key type lgssc unregistered [11308.344784] LNet: 366250:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11308.355512] LNetError: 366250:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11308.382335] LNet: Removed LNI 192.168.202.2@tcp [11308.954156] Key type .llcrypt unregistered [11308.958494] Key type ._llcrypt unregistered [11309.613861] Key type ._llcrypt registered [11309.615356] Key type .llcrypt registered [11310.069140] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11310.084458] alg: No test for adler32 (adler32-zlib) [11311.160893] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11311.363070] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11313.023207] Key type lgssc registered [11314.083377] Lustre: Echo OBD driver; http://www.lustre.org/ [11325.483646] Lustre: DEBUG MARKER: Iteration 20 [11325.900132] LustreError: 367081:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11325.905483] LustreError: 367088:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11325.915098] LustreError: 367081:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11328.313672] Lustre: Mounted lustre-client [11329.697077] LustreError: 367191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0459e18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11329.717188] LustreError: 367191:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11329.770333] Lustre: Unmounted lustre-client [11332.796717] Key type lgssc unregistered [11333.093902] LNet: 367440:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11333.109267] LNetError: 367440:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11333.134655] LNet: Removed LNI 192.168.202.2@tcp [11333.743276] Key type .llcrypt unregistered [11333.745072] Key type ._llcrypt unregistered [11334.493328] Key type ._llcrypt registered [11334.494832] Key type .llcrypt registered [11335.024798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11335.045933] alg: No test for adler32 (adler32-zlib) [11336.127877] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11336.415975] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11338.087203] Key type lgssc registered [11339.279682] Lustre: Echo OBD driver; http://www.lustre.org/ [11350.088837] Lustre: DEBUG MARKER: Iteration 21 [11350.350608] LustreError: 368261:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11350.351629] LustreError: 368272:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11350.360732] LustreError: 368261:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11351.676780] Lustre: Mounted lustre-client [11352.799755] LustreError: 368382:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0460d10000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11352.822568] LustreError: 368382:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11352.897866] Lustre: Unmounted lustre-client [11355.328315] Key type lgssc unregistered [11355.586569] LNet: 368630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11355.603197] LNetError: 368630:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11355.627386] LNet: Removed LNI 192.168.202.2@tcp [11356.146793] Key type .llcrypt unregistered [11356.148363] Key type ._llcrypt unregistered [11356.970660] Key type ._llcrypt registered [11356.975107] Key type .llcrypt registered [11357.357281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11357.375767] alg: No test for adler32 (adler32-zlib) [11358.464708] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11358.744215] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11360.471178] Key type lgssc registered [11361.598549] Lustre: Echo OBD driver; http://www.lustre.org/ [11370.925598] Lustre: DEBUG MARKER: Iteration 22 [11371.195517] LustreError: 369459:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11371.202029] LustreError: 369461:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11371.214308] LustreError: 369459:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [11373.497960] Lustre: Mounted lustre-client [11374.569721] LustreError: 369571:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04602fc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11374.603453] LustreError: 369571:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11374.702552] Lustre: Unmounted lustre-client [11377.252158] Key type lgssc unregistered [11377.505361] LNet: 369816:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11377.512086] LNetError: 369816:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11378.538494] LNet: Removed LNI 192.168.202.2@tcp [11379.175171] Key type .llcrypt unregistered [11379.179097] Key type ._llcrypt unregistered [11379.754922] Key type ._llcrypt registered [11379.760401] Key type .llcrypt registered [11380.296541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11380.320437] alg: No test for adler32 (adler32-zlib) [11381.454073] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11381.642738] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11383.319220] Key type lgssc registered [11384.728256] Lustre: Echo OBD driver; http://www.lustre.org/ [11394.324066] Lustre: DEBUG MARKER: Iteration 23 [11394.761557] LustreError: 370648:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11394.764346] LustreError: 370649:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11394.783839] LustreError: 370648:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [11397.050308] Lustre: Mounted lustre-client [11398.086526] LustreError: 370761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450f4c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11398.098832] LustreError: 370761:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11398.102828] LustreError: 370761:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11398.159115] Lustre: Unmounted lustre-client [11400.548941] Key type lgssc unregistered [11400.803625] LNet: 371005:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11400.811747] LNetError: 371005:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11400.839066] LNet: Removed LNI 192.168.202.2@tcp [11401.386200] Key type .llcrypt unregistered [11401.392225] Key type ._llcrypt unregistered [11401.926023] Key type ._llcrypt registered [11401.927620] Key type .llcrypt registered [11402.486889] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11402.496337] alg: No test for adler32 (adler32-zlib) [11403.591360] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11403.803868] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11405.471228] Key type lgssc registered [11406.610587] Lustre: Echo OBD driver; http://www.lustre.org/ [11415.915652] Lustre: DEBUG MARKER: Iteration 24 [11416.224848] LustreError: 371831:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11416.227930] LustreError: 371835:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11416.238589] LustreError: 371831:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11417.520912] Lustre: Mounted lustre-client [11418.852716] LustreError: 371942:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0445af8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11418.882270] LustreError: 371942:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11418.969652] Lustre: Unmounted lustre-client [11421.669566] Key type lgssc unregistered [11421.961815] LNet: 372192:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11421.976087] LNetError: 372192:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11421.989604] LNet: Removed LNI 192.168.202.2@tcp [11422.661198] Key type .llcrypt unregistered [11422.663592] Key type ._llcrypt unregistered [11423.397152] Key type ._llcrypt registered [11423.399256] Key type .llcrypt registered [11423.845301] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11423.873486] alg: No test for adler32 (adler32-zlib) [11424.990732] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11425.280724] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11426.967281] Key type lgssc registered [11428.071343] Lustre: Echo OBD driver; http://www.lustre.org/ [11438.183980] Lustre: DEBUG MARKER: Iteration 25 [11438.484090] LustreError: 373023:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11438.487747] LustreError: 373024:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11438.502936] LustreError: 373023:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11439.882230] Lustre: Mounted lustre-client [11441.085337] LustreError: 373133:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0446dcf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11441.102105] LustreError: 373133:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11441.117792] LustreError: 373141:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11441.188698] Lustre: Unmounted lustre-client [11443.722424] Key type lgssc unregistered [11443.992555] LNet: 373383:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11444.003429] LNetError: 373383:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11445.034868] LNet: Removed LNI 192.168.202.2@tcp [11445.683188] Key type .llcrypt unregistered [11445.686349] Key type ._llcrypt unregistered [11446.111105] Key type ._llcrypt registered [11446.113257] Key type .llcrypt registered [11446.425348] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11446.437368] alg: No test for adler32 (adler32-zlib) [11447.438269] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11447.650570] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11449.319220] Key type lgssc registered [11450.246046] Lustre: Echo OBD driver; http://www.lustre.org/ [11460.150607] Lustre: DEBUG MARKER: Iteration 26 [11460.410474] LustreError: 374209:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11460.413661] LustreError: 374211:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11460.418116] LustreError: 374209:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11461.660642] Lustre: Mounted lustre-client [11462.814794] LustreError: 374325:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0445afa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11462.827864] LustreError: 374325:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11462.877849] Lustre: Unmounted lustre-client [11464.668299] Key type lgssc unregistered [11464.902851] LNet: 374570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11464.909897] LNetError: 374570:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11464.922809] LNet: Removed LNI 192.168.202.2@tcp [11465.332405] Key type .llcrypt unregistered [11465.334366] Key type ._llcrypt unregistered [11465.703731] Key type ._llcrypt registered [11465.705303] Key type .llcrypt registered [11465.981359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11465.994378] alg: No test for adler32 (adler32-zlib) [11467.021200] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11467.196722] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11468.839208] Key type lgssc registered [11469.689806] Lustre: Echo OBD driver; http://www.lustre.org/ [11478.619959] Lustre: DEBUG MARKER: Iteration 27 [11478.847439] LustreError: 375402:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11478.848221] LustreError: 375401:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11478.860171] LustreError: 375402:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11480.078684] Lustre: Mounted lustre-client [11480.084079] Lustre: Skipped 1 previous similar message [11481.135534] LustreError: 375521:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04582f7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11481.161447] LustreError: 375521:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11481.225171] Lustre: Unmounted lustre-client [11483.139566] Key type lgssc unregistered [11483.380739] LNet: 375763:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11483.385477] LNetError: 375763:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11483.401901] LNet: Removed LNI 192.168.202.2@tcp [11483.821184] Key type .llcrypt unregistered [11483.822947] Key type ._llcrypt unregistered [11484.314914] Key type ._llcrypt registered [11484.316638] Key type .llcrypt registered [11484.617254] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11484.643502] alg: No test for adler32 (adler32-zlib) [11485.735722] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11485.964378] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11487.671151] Key type lgssc registered [11488.915273] Lustre: Echo OBD driver; http://www.lustre.org/ [11498.937301] Lustre: DEBUG MARKER: Iteration 28 [11499.331631] LustreError: 376588:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11499.335995] LustreError: 376597:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11499.342982] LustreError: 376588:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11500.659896] Lustre: Mounted lustre-client [11502.027454] LustreError: 376709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c044791a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11502.047729] LustreError: 376709:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11502.052979] LustreError: 376709:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11502.115670] Lustre: Unmounted lustre-client [11504.804685] Key type lgssc unregistered [11505.058539] LNet: 376951:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11505.075065] LNetError: 376951:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11505.088323] LNet: Removed LNI 192.168.202.2@tcp [11505.638528] Key type .llcrypt unregistered [11505.644094] Key type ._llcrypt unregistered [11506.645832] Key type ._llcrypt registered [11506.674666] Key type .llcrypt registered [11507.350583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11507.371333] alg: No test for adler32 (adler32-zlib) [11508.586881] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11508.794053] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11510.511229] Key type lgssc registered [11511.829829] Lustre: Echo OBD driver; http://www.lustre.org/ [11522.414353] Lustre: DEBUG MARKER: Iteration 29 [11523.453082] LustreError: 377783:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11523.456208] LustreError: 377785:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11523.461198] LustreError: 377783:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11524.816621] Lustre: Mounted lustre-client [11526.468851] LustreError: 377888:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047429d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11526.515216] LustreError: 377888:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11526.646163] Lustre: Unmounted lustre-client [11529.410691] Key type lgssc unregistered [11529.747389] LNet: 378138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11529.754286] LNetError: 378138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11529.781099] LNet: Removed LNI 192.168.202.2@tcp [11530.519127] Key type .llcrypt unregistered [11530.520617] Key type ._llcrypt unregistered [11531.288240] Key type ._llcrypt registered [11531.289751] Key type .llcrypt registered [11531.760383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11531.788702] alg: No test for adler32 (adler32-zlib) [11532.906496] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11533.214032] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11534.943957] Key type lgssc registered [11536.534505] Lustre: Echo OBD driver; http://www.lustre.org/ [11546.422361] Lustre: DEBUG MARKER: Iteration 30 [11546.778255] LustreError: 378963:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11546.782726] LustreError: 378967:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11546.785790] LustreError: 378963:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11548.069252] Lustre: Mounted lustre-client [11548.087297] Lustre: Skipped 1 previous similar message [11549.356788] LustreError: 379075:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c045166e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11549.383778] LustreError: 379075:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11549.478990] Lustre: Unmounted lustre-client [11551.942220] Key type lgssc unregistered [11552.140281] LNet: 379324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11552.154950] LNetError: 379324:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11552.179568] LNet: Removed LNI 192.168.202.2@tcp [11552.701308] Key type .llcrypt unregistered [11552.707137] Key type ._llcrypt unregistered [11553.464041] Key type ._llcrypt registered [11553.469240] Key type .llcrypt registered [11553.667297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11553.683156] alg: No test for adler32 (adler32-zlib) [11554.680385] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11554.894035] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11556.583191] Key type lgssc registered [11557.733435] Lustre: Echo OBD driver; http://www.lustre.org/ [11568.361066] Lustre: DEBUG MARKER: Iteration 31 [11568.753132] LustreError: 380153:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11568.758806] LustreError: 380155:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11568.776442] LustreError: 380153:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [11570.144992] Lustre: Mounted lustre-client [11570.147194] Lustre: Skipped 1 previous similar message [11571.374044] LustreError: 380264:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04688f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11571.404046] LustreError: 380264:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11571.482944] Lustre: Unmounted lustre-client [11574.125478] Key type lgssc unregistered [11574.357592] LNet: 380514:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11574.364363] LNetError: 380514:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11574.384713] LNet: Removed LNI 192.168.202.2@tcp [11575.025627] Key type .llcrypt unregistered [11575.027229] Key type ._llcrypt unregistered [11575.858355] Key type ._llcrypt registered [11575.885392] Key type .llcrypt registered [11576.156375] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11576.170675] alg: No test for adler32 (adler32-zlib) [11577.354617] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11577.526339] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11579.207346] Key type lgssc registered [11580.385173] Lustre: Echo OBD driver; http://www.lustre.org/ [11590.226632] Lustre: DEBUG MARKER: Iteration 32 [11590.508814] LustreError: 381345:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11590.510542] LustreError: 381347:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11590.520865] LustreError: 381345:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11591.807702] Lustre: Mounted lustre-client [11591.816185] Lustre: Skipped 1 previous similar message [11593.111855] LustreError: 381452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0460d16000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11593.136148] LustreError: 381452:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11593.246663] Lustre: Unmounted lustre-client [11596.271554] Key type lgssc unregistered [11596.615129] LNet: 381701:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11596.634088] LNetError: 381701:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11596.671485] LNet: Removed LNI 192.168.202.2@tcp [11597.404199] Key type .llcrypt unregistered [11597.406128] Key type ._llcrypt unregistered [11597.905365] Key type ._llcrypt registered [11597.906914] Key type .llcrypt registered [11598.354641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11598.372417] alg: No test for adler32 (adler32-zlib) [11599.446217] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11599.816268] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11601.543186] Key type lgssc registered [11602.701844] Lustre: Echo OBD driver; http://www.lustre.org/ [11614.500453] Lustre: DEBUG MARKER: Iteration 33 [11614.853964] LustreError: 382531:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11614.854037] LustreError: 382532:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11614.870474] LustreError: 382531:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11616.162852] Lustre: Mounted lustre-client [11617.668811] LustreError: 382650:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04689da000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11617.677119] LustreError: 382650:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11617.681431] LustreError: 382651:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11617.743546] Lustre: Unmounted lustre-client [11620.496610] Key type lgssc unregistered [11620.782663] LNet: 382893:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11620.790616] LNetError: 382893:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11621.867820] LNet: Removed LNI 192.168.202.2@tcp [11622.723284] Key type .llcrypt unregistered [11622.730195] Key type ._llcrypt unregistered [11623.426454] Key type ._llcrypt registered [11623.428268] Key type .llcrypt registered [11623.960592] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11623.976829] alg: No test for adler32 (adler32-zlib) [11625.114503] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11625.337149] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11627.036084] Key type lgssc registered [11628.338795] Lustre: Echo OBD driver; http://www.lustre.org/ [11638.562530] Lustre: DEBUG MARKER: Iteration 34 [11638.765802] LustreError: 383716:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11638.767412] LustreError: 383734:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11638.773588] LustreError: 383716:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11640.009702] Lustre: Mounted lustre-client [11641.294580] LustreError: 383840:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450986000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11641.310908] LustreError: 383840:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11641.322053] LustreError: 383840:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11641.382406] Lustre: Unmounted lustre-client [11644.179351] Key type lgssc unregistered [11644.453060] LNet: 384081:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11644.468584] LNetError: 384081:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11645.485815] LNet: Removed LNI 192.168.202.2@tcp [11646.167115] Key type .llcrypt unregistered [11646.168820] Key type ._llcrypt unregistered [11646.711654] Key type ._llcrypt registered [11646.713233] Key type .llcrypt registered [11647.271707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11647.288198] alg: No test for adler32 (adler32-zlib) [11648.308681] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11648.510510] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11650.176726] Key type lgssc registered [11651.280503] Lustre: Echo OBD driver; http://www.lustre.org/ [11661.497341] Lustre: DEBUG MARKER: Iteration 35 [11661.859937] LustreError: 384912:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11661.865171] LustreError: 384913:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11661.873101] LustreError: 384912:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11663.162366] Lustre: Mounted lustre-client [11664.316615] LustreError: 385027:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450f4d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11664.343549] LustreError: 385027:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11664.393297] Lustre: Unmounted lustre-client [11666.668677] Key type lgssc unregistered [11666.880418] LNet: 385270:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11666.888948] LNetError: 385270:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11666.917185] LNet: Removed LNI 192.168.202.2@tcp [11667.506429] Key type .llcrypt unregistered [11667.508173] Key type ._llcrypt unregistered [11668.058615] Key type ._llcrypt registered [11668.061612] Key type .llcrypt registered [11668.400051] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11668.410654] alg: No test for adler32 (adler32-zlib) [11669.540144] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11669.768293] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11671.431176] Key type lgssc registered [11672.626515] Lustre: Echo OBD driver; http://www.lustre.org/ [11683.514746] Lustre: DEBUG MARKER: Iteration 36 [11683.820836] LustreError: 386097:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11683.864258] LustreError: 386105:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11683.869282] LustreError: 386097:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4956 [11685.319047] Lustre: Mounted lustre-client [11686.906959] LustreError: 386212:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04514f6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11686.933687] LustreError: 386212:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11687.045443] Lustre: Unmounted lustre-client [11689.708561] Key type lgssc unregistered [11689.965306] LNet: 386454:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11689.984132] LNetError: 386454:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11690.006589] LNet: Removed LNI 192.168.202.2@tcp [11690.781347] Key type .llcrypt unregistered [11690.786078] Key type ._llcrypt unregistered [11691.572466] Key type ._llcrypt registered [11691.574296] Key type .llcrypt registered [11692.076478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11692.094695] alg: No test for adler32 (adler32-zlib) [11693.254176] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11693.478138] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11695.144394] Key type lgssc registered [11696.806225] Lustre: Echo OBD driver; http://www.lustre.org/ [11708.780996] Lustre: DEBUG MARKER: Iteration 37 [11709.155378] LustreError: 387279:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11709.203096] LustreError: 387299:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11709.206067] LustreError: 387279:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [11710.641357] Lustre: Mounted lustre-client [11712.330591] LustreError: 387392:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0446dcd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11712.363567] LustreError: 387392:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11712.502266] Lustre: Unmounted lustre-client [11716.047817] Key type lgssc unregistered [11716.380698] LNet: 387641:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11716.397716] LNetError: 387641:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11716.425541] LNet: Removed LNI 192.168.202.2@tcp [11717.379216] Key type .llcrypt unregistered [11717.383819] Key type ._llcrypt unregistered [11718.480332] Key type ._llcrypt registered [11718.481903] Key type .llcrypt registered [11719.129044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11719.149933] alg: No test for adler32 (adler32-zlib) [11720.306810] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11720.611523] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11722.359302] Key type lgssc registered [11723.943841] Lustre: Echo OBD driver; http://www.lustre.org/ [11735.389159] Lustre: DEBUG MARKER: Iteration 38 [11735.801935] LustreError: 388469:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11735.805359] LustreError: 388477:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11735.811866] LustreError: 388469:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11737.124316] Lustre: Mounted lustre-client [11738.452873] LustreError: 388582:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04517d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11738.494110] LustreError: 388582:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11738.536755] Lustre: Unmounted lustre-client [11742.193729] Key type lgssc unregistered [11742.617291] LNet: 388830:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11742.634157] LNetError: 388830:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11742.652719] LNet: Removed LNI 192.168.202.2@tcp [11743.503213] Key type .llcrypt unregistered [11743.507931] Key type ._llcrypt unregistered [11745.108911] Key type ._llcrypt registered [11745.110803] Key type .llcrypt registered [11745.398232] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11745.424971] alg: No test for adler32 (adler32-zlib) [11746.620488] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11746.929321] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11748.687476] Key type lgssc registered [11750.555356] Lustre: Echo OBD driver; http://www.lustre.org/ [11763.652255] Lustre: DEBUG MARKER: Iteration 39 [11764.111127] LustreError: 389660:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11764.112170] LustreError: 389662:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11764.124598] LustreError: 389660:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11765.529905] Lustre: Mounted lustre-client [11766.837593] LustreError: 389774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c044612e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11766.854437] LustreError: 389773:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11766.856710] LustreError: 389774:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11766.962661] Lustre: Unmounted lustre-client [11769.946381] Key type lgssc unregistered [11770.218186] LNet: 390015:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11770.242791] LNetError: 390015:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11770.263505] LNet: Removed LNI 192.168.202.2@tcp [11770.975233] Key type .llcrypt unregistered [11770.978532] Key type ._llcrypt unregistered [11771.861207] Key type ._llcrypt registered [11771.870421] Key type .llcrypt registered [11772.418878] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11772.446908] alg: No test for adler32 (adler32-zlib) [11773.738303] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11774.050981] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11775.719240] Key type lgssc registered [11776.966566] Lustre: Echo OBD driver; http://www.lustre.org/ [11789.752533] Lustre: DEBUG MARKER: Iteration 40 [11790.390142] LustreError: 390845:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11790.392853] LustreError: 390847:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11790.401777] LustreError: 390845:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11791.738988] Lustre: Mounted lustre-client [11793.032835] LustreError: 390962:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450441800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11793.047396] LustreError: 390962:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11793.060119] LustreError: 390963:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11793.164071] Lustre: Unmounted lustre-client [11796.337856] Key type lgssc unregistered [11796.603524] LNet: 391205:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11796.614813] LNetError: 391205:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11796.636676] LNet: Removed LNI 192.168.202.2@tcp [11797.542412] Key type .llcrypt unregistered [11797.547628] Key type ._llcrypt unregistered [11798.626688] Key type ._llcrypt registered [11798.628345] Key type .llcrypt registered [11799.094554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11799.108788] alg: No test for adler32 (adler32-zlib) [11800.348383] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11800.665415] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11802.439879] Key type lgssc registered [11804.006810] Lustre: Echo OBD driver; http://www.lustre.org/ [11816.561557] Lustre: DEBUG MARKER: Iteration 41 [11816.945906] LustreError: 392030:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11816.948947] LustreError: 392037:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11816.967327] LustreError: 392030:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11818.351601] Lustre: Mounted lustre-client [11819.692819] LustreError: 392146:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04703ee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11819.719478] LustreError: 392146:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11819.820738] Lustre: Unmounted lustre-client [11823.480849] Key type lgssc unregistered [11823.779756] LNet: 392396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11823.796914] LNetError: 392396:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11823.838120] LNet: Removed LNI 192.168.202.2@tcp [11824.675522] Key type .llcrypt unregistered [11824.677233] Key type ._llcrypt unregistered [11826.157229] Key type ._llcrypt registered [11826.158745] Key type .llcrypt registered [11826.724223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11826.750097] alg: No test for adler32 (adler32-zlib) [11827.925901] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11828.208888] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11829.887386] Key type lgssc registered [11831.291230] Lustre: Echo OBD driver; http://www.lustre.org/ [11843.976885] Lustre: DEBUG MARKER: Iteration 42 [11844.303062] LustreError: 393222:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11844.306490] LustreError: 393230:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11844.321302] LustreError: 393222:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11845.692636] Lustre: Mounted lustre-client [11847.144685] LustreError: 393340:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0443acb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11847.167848] LustreError: 393340:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11847.170628] LustreError: 393344:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11847.254465] Lustre: Unmounted lustre-client [11850.129065] Key type lgssc unregistered [11850.404280] LNet: 393585:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11850.423496] LNetError: 393585:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11850.441594] LNet: Removed LNI 192.168.202.2@tcp [11851.099232] Key type .llcrypt unregistered [11851.106322] Key type ._llcrypt unregistered [11852.074334] Key type ._llcrypt registered [11852.076398] Key type .llcrypt registered [11852.587504] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11852.612287] alg: No test for adler32 (adler32-zlib) [11853.619479] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11853.841585] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11855.527145] Key type lgssc registered [11856.672606] Lustre: Echo OBD driver; http://www.lustre.org/ [11868.200775] Lustre: DEBUG MARKER: Iteration 43 [11868.761718] LustreError: 394414:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11868.765487] LustreError: 394415:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11868.775419] LustreError: 394414:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11870.153208] Lustre: Mounted lustre-client [11871.665476] LustreError: 394526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04767c3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11871.689272] LustreError: 394526:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11871.699712] LustreError: 394526:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11871.828887] Lustre: Unmounted lustre-client [11874.633721] Key type lgssc unregistered [11874.951274] LNet: 394776:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11874.962471] LNetError: 394776:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11874.974419] LNet: Removed LNI 192.168.202.2@tcp [11875.667219] Key type .llcrypt unregistered [11875.677099] Key type ._llcrypt unregistered [11876.714809] Key type ._llcrypt registered [11876.716417] Key type .llcrypt registered [11876.924626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11876.942021] alg: No test for adler32 (adler32-zlib) [11877.981868] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11878.192815] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11879.895516] Key type lgssc registered [11881.096777] Lustre: Echo OBD driver; http://www.lustre.org/ [11894.090699] Lustre: DEBUG MARKER: Iteration 44 [11894.623208] LustreError: 395607:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11894.623750] LustreError: 395605:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11894.644193] LustreError: 395607:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [11897.174746] Lustre: Mounted lustre-client [11899.002189] LustreError: 395721:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450f49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11899.038885] LustreError: 395721:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11899.112205] Lustre: Unmounted lustre-client [11902.176344] Key type lgssc unregistered [11902.433054] LNet: 395968:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11902.454595] LNetError: 395968:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11902.478194] LNet: Removed LNI 192.168.202.2@tcp [11903.310348] Key type .llcrypt unregistered [11903.314975] Key type ._llcrypt unregistered [11904.481128] Key type ._llcrypt registered [11904.482877] Key type .llcrypt registered [11904.872765] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11904.899372] alg: No test for adler32 (adler32-zlib) [11906.109886] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11906.473166] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11908.250635] Key type lgssc registered [11909.664735] Lustre: Echo OBD driver; http://www.lustre.org/ [11922.378405] Lustre: DEBUG MARKER: Iteration 45 [11922.943679] LustreError: 396797:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11922.948039] LustreError: 396806:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11922.965652] LustreError: 396797:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11924.376322] Lustre: Mounted lustre-client [11925.832728] LustreError: 396915:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450984800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11925.860824] LustreError: 396915:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11925.864725] LustreError: 396915:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11925.953165] Lustre: Unmounted lustre-client [11928.760493] Key type lgssc unregistered [11929.061449] LNet: 397156:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11929.075213] LNetError: 397156:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11930.107495] LNet: Removed LNI 192.168.202.2@tcp [11930.877255] Key type .llcrypt unregistered [11930.879944] Key type ._llcrypt unregistered [11931.575559] Key type ._llcrypt registered [11931.577261] Key type .llcrypt registered [11932.019950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11932.043840] alg: No test for adler32 (adler32-zlib) [11933.152900] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11933.349726] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11935.087211] Key type lgssc registered [11936.408941] Lustre: Echo OBD driver; http://www.lustre.org/ [11947.688426] Lustre: DEBUG MARKER: Iteration 46 [11948.049730] LustreError: 397987:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11948.052334] LustreError: 397988:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11948.078238] LustreError: 397987:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [11949.414639] Lustre: Mounted lustre-client [11950.484304] LustreError: 398096:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0450443800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11950.498578] LustreError: 398096:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11950.621956] Lustre: Unmounted lustre-client [11953.392479] Key type lgssc unregistered [11953.686486] LNet: 398345:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11953.701382] LNetError: 398345:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11954.728567] LNet: Removed LNI 192.168.202.2@tcp [11955.409193] Key type .llcrypt unregistered [11955.410437] Key type ._llcrypt unregistered [11956.396834] Key type ._llcrypt registered [11956.398330] Key type .llcrypt registered [11956.590685] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11956.604700] alg: No test for adler32 (adler32-zlib) [11957.649681] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11957.970508] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11959.711329] Key type lgssc registered [11961.164588] Lustre: Echo OBD driver; http://www.lustre.org/ [11973.985573] Lustre: DEBUG MARKER: Iteration 47 [11974.316340] LustreError: 399172:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11974.334963] LustreError: 399190:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11974.339301] LustreError: 399172:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4977 [11975.687449] Lustre: Mounted lustre-client [11977.175547] LustreError: 399292:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047decd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11977.197949] LustreError: 399292:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11977.269283] Lustre: Unmounted lustre-client [11979.996346] Key type lgssc unregistered [11980.253142] LNet: 399534:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11980.262325] LNetError: 399534:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11981.285481] LNet: Removed LNI 192.168.202.2@tcp [11982.099452] Key type .llcrypt unregistered [11982.101158] Key type ._llcrypt unregistered [11982.803650] Key type ._llcrypt registered [11982.805335] Key type .llcrypt registered [11983.282225] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11983.310208] alg: No test for adler32 (adler32-zlib) [11984.424343] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [11984.658762] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [11986.327407] Key type lgssc registered [11987.487426] Lustre: Echo OBD driver; http://www.lustre.org/ [11999.098612] Lustre: DEBUG MARKER: Iteration 48 [11999.346499] LustreError: 400362:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11999.350802] LustreError: 400363:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11999.358234] LustreError: 400362:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12000.631380] Lustre: Mounted lustre-client [12001.886065] LustreError: 400481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c04516d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12001.903458] LustreError: 400481:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12001.914172] LustreError: 400480:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12001.973174] Lustre: Unmounted lustre-client [12004.453458] Key type lgssc unregistered [12004.711737] LNet: 400723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12004.722238] LNetError: 400723:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12004.742636] LNet: Removed LNI 192.168.202.2@tcp [12005.270173] Key type .llcrypt unregistered [12005.272094] Key type ._llcrypt unregistered [12006.033189] Key type ._llcrypt registered [12006.035559] Key type .llcrypt registered [12006.353548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12006.362752] alg: No test for adler32 (adler32-zlib) [12007.445131] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [12007.680046] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [12009.359803] Key type lgssc registered [12010.581228] Lustre: Echo OBD driver; http://www.lustre.org/ [12021.540139] Lustre: DEBUG MARKER: Iteration 49 [12021.947769] LustreError: 401552:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12021.971307] LustreError: 401562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12021.976892] LustreError: 401552:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [12023.275697] Lustre: Mounted lustre-client [12024.478080] LustreError: 401661:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c046005d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12024.492773] LustreError: 401661:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12024.569180] Lustre: Unmounted lustre-client [12027.321923] Key type lgssc unregistered [12027.650643] LNet: 401911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12027.662732] LNetError: 401911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12027.690474] LNet: Removed LNI 192.168.202.2@tcp [12028.371572] Key type .llcrypt unregistered [12028.376871] Key type ._llcrypt unregistered [12029.043308] Key type ._llcrypt registered [12029.054717] Key type .llcrypt registered [12029.613403] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12029.634785] alg: No test for adler32 (adler32-zlib) [12030.732609] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [12030.954359] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [12032.639272] Key type lgssc registered [12034.173234] Lustre: Echo OBD driver; http://www.lustre.org/ [12044.710288] Lustre: DEBUG MARKER: Iteration 50 [12044.997429] LustreError: 402752:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12044.998409] LustreError: 402750:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12045.008520] LustreError: 402752:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12046.415940] Lustre: Mounted lustre-client [12047.718250] LustreError: 402863:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c0446dca800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12047.747578] LustreError: 402863:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12047.814138] Lustre: Unmounted lustre-client [12050.539287] Key type lgssc unregistered [12050.864975] LNet: 403109:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12050.871269] LNetError: 403109:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12050.887231] LNet: Removed LNI 192.168.202.2@tcp [12051.707278] Key type .llcrypt unregistered [12051.713456] Key type ._llcrypt unregistered [12052.461811] Key type ._llcrypt registered [12052.463393] Key type .llcrypt registered [12053.215492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12053.254133] alg: No test for adler32 (adler32-zlib) [12054.469409] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [12054.749264] LNet: Added LNI 192.168.202.2@tcp [8/256/0/180] [12056.447395] Key type lgssc registered [12057.762843] Lustre: Echo OBD driver; http://www.lustre.org/ [12068.044548] Lustre: Mounted lustre-client [12075.667611] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:48:43 (1763344123) [12093.409143] Lustre: 404468:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344126/real 1763344126] req@ffff9c0467a0c700 x1849000307207168/t0(0) o36->lustre-MDT0000-mdc-ffff9c047dcf5000@192.168.202.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344142 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12093.451518] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection to lustre-MDT0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12093.504700] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12109.791351] Lustre: 404468:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344143/real 1763344143] req@ffff9c0467a0c700 x1849000307207168/t0(0) o36->lustre-MDT0000-mdc-ffff9c047dcf5000@192.168.202.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344159 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12109.827492] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection to lustre-MDT0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12109.847124] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12126.175207] Lustre: 404468:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344159/real 1763344159] req@ffff9c0467a0c700 x1849000307207168/t0(0) o36->lustre-MDT0000-mdc-ffff9c047dcf5000@192.168.202.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344175 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12126.203875] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection to lustre-MDT0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12126.259506] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12141.552093] Lustre: 404468:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344175/real 1763344175] req@ffff9c0467a0c700 x1849000307207168/t0(0) o36->lustre-MDT0000-mdc-ffff9c047dcf5000@192.168.202.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1763344191 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12141.573616] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection to lustre-MDT0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12141.602224] Lustre: lustre-MDT0000-mdc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12146.758910] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:49:54 (1763344194) [12159.009514] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:50:07 (1763344207) [12172.793673] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:50:20 (1763344220) [12179.997179] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:50:28 (1763344228) [12204.757967] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:50:52 (1763344252) [12244.728225] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:51:32 (1763344292) [12252.836199] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:51:40 (1763344300) [12276.705529] Lustre: 403302:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344310/real 1763344310] req@ffff9c047dc04380 x1849000308254720/t0(0) o4->lustre-OST0000-osc-ffff9c047dcf5000@192.168.202.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344326 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12276.706372] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection to lustre-OST0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12276.743962] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12276.755937] Lustre: 403302:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 7 previous similar messages [12293.087234] Lustre: 403301:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344326/real 1763344326] req@ffff9c047dc04700 x1849000308253952/t0(0) o4->lustre-OST0000-osc-ffff9c047dcf5000@192.168.202.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344342 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12293.087447] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection to lustre-OST0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12293.100564] Lustre: 403301:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [12293.156613] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12324.831223] Lustre: 403302:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763344358/real 1763344358] req@ffff9c047dc04e00 x1849000308253312/t0(0) o4->lustre-OST0000-osc-ffff9c047dcf5000@192.168.202.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763344374 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [12324.848366] Lustre: 403302:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [12324.854692] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection to lustre-OST0000 (at 192.168.202.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [12324.862693] Lustre: Skipped 1 previous similar message [12324.898848] Lustre: lustre-OST0000-osc-ffff9c047dcf5000: Connection restored to 192.168.202.102@tcp (at 192.168.202.102@tcp) [12324.907029] Lustre: Skipped 1 previous similar message [12354.106713] Lustre: DEBUG MARKER: oleg202-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c046af7b800.ost_server_uuid 50 [12355.526215] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c046af7b800.ost_server_uuid in IDLE state after 0 sec [12356.942630] Lustre: DEBUG MARKER: cleanup: ====================================================== [12358.609762] Lustre: DEBUG MARKER: == sanityn test complete, duration 12113 sec ============= 20:53:26 (1763344406) [12360.077338] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:53:28 (1763344408) === [12681.662457] LustreError: 410758:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c046af7b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12681.678954] LustreError: 410758:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12681.740960] Lustre: Unmounted lustre-client [12684.755529] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:58:53 (1763344733) === [12685.937903] LustreError: 411065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c047dcf5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12685.948383] LustreError: 411065:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12685.964102] LustreError: 411065:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12685.969863] LustreError: 411065:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [12686.038110] Lustre: Unmounted lustre-client [12722.579369] Key type lgssc unregistered [12722.765094] LNet: 411749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12722.773733] LNetError: 411749:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12722.798541] LNet: Removed LNI 192.168.202.2@tcp [12723.413305] Key type .llcrypt unregistered [12723.416500] Key type ._llcrypt unregistered