[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 1181506336 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2876856K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K 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.001011] APIC: Switch to symmetric I/O mode setup [ 0.003329] x2apic enabled [ 0.004008] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.008296] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011099] LSM: Security Framework initializing [ 0.012030] Yama: becoming mindful. [ 0.013027] SELinux: Initializing. [ 0.014055] *** VALIDATE selinux *** [ 0.021572] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025385] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026156] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028117] *** VALIDATE tmpfs *** [ 0.030099] *** VALIDATE proc *** [ 0.031000] *** VALIDATE cgroup *** [ 0.031012] *** VALIDATE cgroup2 *** [ 0.032284] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.033167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.034008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.035032] Spectre V2 : User space: Vulnerable [ 0.036009] Speculative Store Bypass: Vulnerable [ 0.039251] debug: unmapping init [mem 0xffffffff8bc59000-0xffffffff8bc60fff] [ 0.042194] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043733] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044030] ... version: 2 [ 0.045014] ... bit width: 48 [ 0.046007] ... generic registers: 4 [ 0.046886] ... value mask: 0000ffffffffffff [ 0.047011] ... max period: 00007fffffffffff [ 0.048014] ... fixed-purpose events: 3 [ 0.049014] ... event mask: 000000070000000f [ 0.051237] rcu: Hierarchical SRCU implementation. [ 0.053480] smp: Bringing up secondary CPUs ... [ 0.054537] x86: Booting SMP configuration: [ 0.055028] .... node #0, CPUs: #1 #2 #3 [ 0.059289] smp: Brought up 1 node, 4 CPUs [ 0.061014] smpboot: Max logical packages: 1 [ 0.062019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.240028] node 0 deferred pages initialised in 177ms [ 0.244015] devtmpfs: initialized [ 0.245264] x86/mm: Memory block size: 128MB [ 0.248049] gcov: version magic: 0x41383552 [ 0.250410] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.251097] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.252368] pinctrl core: initialized pinctrl subsystem [ 0.253258] [ 0.253860] ************************************************************* [ 0.254018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.255019] ** ** [ 0.256019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.257022] ** ** [ 0.258029] ** This means that this kernel is built to expose internal ** [ 0.259018] ** IOMMU data structures, which may compromise security on ** [ 0.260019] ** your system. ** [ 0.261016] ** ** [ 0.262015] ** If you see this message and you are not debugging the ** [ 0.263024] ** kernel, report this immediately to your vendor! ** [ 0.264018] ** ** [ 0.265016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.266020] ************************************************************* [ 0.267902] NET: Registered protocol family 16 [ 0.268562] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.269081] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.270075] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.272011] cpuidle: using governor menu [ 0.273237] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.274535] PCI: Using configuration type 1 for base access [ 0.276108] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.283052] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.284018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.286020] cryptd: max_cpu_qlen set to 1000 [ 0.287265] ACPI: Added _OSI(Module Device) [ 0.288017] ACPI: Added _OSI(Processor Device) [ 0.289012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.290008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.293881] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.295560] ACPI: Interpreter enabled [ 0.296000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.298021] ACPI: Using IOAPIC for interrupt routing [ 0.299139] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.302360] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.312482] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.314045] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.316018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.318137] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.322722] acpiphp: Slot [2] registered [ 0.324173] acpiphp: Slot [5] registered [ 0.326245] acpiphp: Slot [6] registered [ 0.327197] acpiphp: Slot [3] registered [ 0.328076] acpiphp: Slot [4] registered [ 0.329000] acpiphp: Slot [7] registered [ 0.330114] acpiphp: Slot [8] registered [ 0.331088] acpiphp: Slot [9] registered [ 0.332109] acpiphp: Slot [10] registered [ 0.333246] acpiphp: Slot [11] registered [ 0.334103] acpiphp: Slot [12] registered [ 0.335082] acpiphp: Slot [13] registered [ 0.336082] acpiphp: Slot [14] registered [ 0.337091] acpiphp: Slot [15] registered [ 0.338153] acpiphp: Slot [16] registered [ 0.339125] acpiphp: Slot [17] registered [ 0.341096] acpiphp: Slot [18] registered [ 0.342000] acpiphp: Slot [19] registered [ 0.342000] acpiphp: Slot [20] registered [ 0.342000] acpiphp: Slot [21] registered [ 0.344060] acpiphp: Slot [22] registered [ 0.344903] acpiphp: Slot [23] registered [ 0.346134] acpiphp: Slot [24] registered [ 0.347142] acpiphp: Slot [25] registered [ 0.349093] acpiphp: Slot [26] registered [ 0.350073] acpiphp: Slot [27] registered [ 0.350962] acpiphp: Slot [28] registered [ 0.351093] acpiphp: Slot [29] registered [ 0.353157] acpiphp: Slot [30] registered [ 0.354115] acpiphp: Slot [31] registered [ 0.356074] PCI host bridge to bus 0000:00 [ 0.357032] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.359059] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.361033] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.363025] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.364023] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.366052] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.367262] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.370062] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.373502] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.380452] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.385412] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.388023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.390033] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.393028] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.395648] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.399108] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.401068] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.404827] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.407731] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.414992] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.420017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.424455] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.428024] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.432016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.441029] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.450529] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.460023] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.467088] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.485021] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.498178] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.501355] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.503636] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.506372] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.508175] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.512178] iommu: Default domain type: Passthrough [ 0.514473] SCSI subsystem initialized [ 0.515102] ACPI: bus type USB registered [ 0.516099] usbcore: registered new interface driver usbfs [ 0.518053] usbcore: registered new interface driver hub [ 0.520073] usbcore: registered new device driver usb [ 0.521145] pps_core: LinuxPPS API ver. 1 registered [ 0.523010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.525081] PTP clock support registered [ 0.527250] EDAC MC: Ver: 3.0.0 [ 0.530336] PCI: Using ACPI for IRQ routing [ 0.531597] NetLabel: Initializing [ 0.534030] NetLabel: domain hash size = 128 [ 0.536015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.539116] NetLabel: unlabeled traffic allowed by default [ 0.542211] vgaarb: loaded [ 0.545044] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.546011] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.552369] clocksource: Switched to clocksource kvm-clock [ 0.662902] VFS: Disk quotas dquot_6.6.0 [ 0.664517] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.666592] *** VALIDATE ramfs *** [ 0.667595] *** VALIDATE hugetlbfs *** [ 0.668970] pnp: PnP ACPI init [ 0.671038] pnp: PnP ACPI: found 6 devices [ 0.692050] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.695653] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.699992] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.702191] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.704551] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.707027] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.709564] NET: Registered protocol family 2 [ 0.711640] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.716504] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.720238] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.726085] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.729697] TCP: Hash tables configured (established 65536 bind 65536) [ 0.732625] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.735881] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.738756] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.743985] NET: Registered protocol family 1 [ 0.746593] RPC: Registered named UNIX socket transport module. [ 0.748811] RPC: Registered udp transport module. [ 0.750541] RPC: Registered tcp transport module. [ 0.751730] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.753612] NET: Registered protocol family 44 [ 0.754820] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.756822] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.758276] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.760757] PCI: CLS 0 bytes, default 64 [ 0.761986] Unpacking initramfs... [ 2.089798] debug: unmapping init [mem 0xffff92387cc64000-0xffff92387ffcffff] [ 2.093644] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.095850] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.098914] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.616156] Initialise system trusted keyrings [ 2.617600] Key type blacklist registered [ 2.619456] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.627601] zbud: loaded [ 2.630221] *** VALIDATE nfs *** [ 2.631372] *** VALIDATE nfs4 *** [ 2.632910] pstore: using deflate compression [ 2.636353] Platform Keyring initialized [ 2.735363] NET: Registered protocol family 38 [ 2.736619] Key type asymmetric registered [ 2.737626] Asymmetric key parser 'x509' registered [ 2.738751] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.741562] io scheduler mq-deadline registered [ 2.742933] io scheduler kyber registered [ 2.744596] io scheduler bfq registered [ 2.745897] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.748119] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.751031] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.753644] ACPI: Power Button [PWRF] [ 2.759956] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.764372] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.773102] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.801673] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.830529] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.834743] Non-volatile memory driver v1.3 [ 2.836558] Linux agpgart interface v0.103 [ 2.871628] virtio_blk virtio1: [vda] 134032 512-byte logical blocks (68.6 MB/65.4 MiB) [ 2.874884] vda: detected capacity change from 0 to 68624384 [ 2.887317] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.889388] vdb: detected capacity change from 0 to 1073741824 [ 2.895150] libphy: Fixed MDIO Bus: probed [ 2.899465] usbcore: registered new interface driver usbserial_generic [ 2.900915] usbserial: USB Serial support registered for generic [ 2.902367] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.905515] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.907350] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.909896] mousedev: PS/2 mouse device common for all mice [ 2.913143] rtc_cmos 00:05: RTC can wake from S4 [ 2.916058] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.917948] rtc_cmos 00:05: registered as rtc0 [ 2.921277] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.922094] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.925264] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.927096] intel_pstate: CPU model not supported [ 2.933338] hid: raw HID events driver (C) Jiri Kosina [ 2.935519] usbcore: registered new interface driver usbhid [ 2.937513] usbhid: USB HID core driver [ 2.939115] drop_monitor: Initializing network drop monitor service [ 2.941634] Initializing XFRM netlink socket [ 2.943582] NET: Registered protocol family 10 [ 2.946448] Segment Routing with IPv6 [ 2.948151] NET: Registered protocol family 17 [ 2.950280] mpls_gso: MPLS GSO support [ 2.956372] RAS: Correctable Errors collector initialized. [ 2.958042] AVX version of gcm_enc/dec engaged. [ 2.959264] AES CTR mode by8 optimization enabled [ 3.019527] sched_clock: Marking stable (3019496800, 0)->(3871646100, -852149300) [ 3.024854] registered taskstats version 1 [ 3.027160] Loading compiled-in X.509 certificates [ 3.029190] zswap: loaded using pool lzo/zbud [ 3.051571] Key type big_key registered [ 3.067258] Key type encrypted registered [ 3.068968] ima: No TPM chip found, activating TPM-bypass! [ 3.070949] ima: Allocated hash algorithm: sha1 [ 3.072847] ima: No architecture policies found [ 3.074864] evm: Initialising EVM extended attributes: [ 3.077321] evm: security.selinux [ 3.078620] evm: security.ima [ 3.079776] evm: security.capability [ 3.081302] evm: HMAC attrs: 0x1 [ 3.083854] rtc_cmos 00:05: setting system clock to 2026-01-22 10:44:53 UTC (1769078693) [ 3.090619] debug: unmapping init [mem 0xffffffff8cc03000-0xffffffff8cdfffff] [ 3.093971] debug: unmapping init [mem 0xffffffff8b982000-0xffffffff8bc58fff] [ 3.101147] Write protecting the kernel read-only data: 28672k [ 3.104847] debug: unmapping init [mem 0xffffffff8a003000-0xffffffff8a1fffff] [ 3.107892] debug: unmapping init [mem 0xffffffff8a914000-0xffffffff8a9fffff] [ 3.140210] 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.149599] systemd[1]: Detected virtualization kvm. [ 3.151724] systemd[1]: Detected architecture x86-64. [ 3.153195] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.178174] systemd[1]: No hostname configured. [ 3.180311] systemd[1]: Set hostname to . [ 3.182599] random: systemd: uninitialized urandom read (16 bytes read) [ 3.185146] systemd[1]: Initializing machine ID from random generator. [ 3.228030] random: ln: uninitialized urandom read (6 bytes read) [ 3.292119] random: systemd: uninitialized urandom read (16 bytes read) [ 3.295229] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.301320] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 3.305394] systemd[1]: Reached target Local Encrypted Volumes. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ 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... [ 3.857442] device-mapper: uevent: version 1.0.3 [ 3.860219] 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... [ 4.158345] random: fast init done [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.506658] virtio_net virtio0 ens2: renamed from eth0 [ 4.572063] scsi host0: ata_piix [ 4.621540] scsi host1: ata_piix [ 4.623287] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.625623] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.301381] dracut-initqueue[578]: RTNETLINK answers: File exists [ 9.592831] random: crng init done [ 9.594420] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.926738] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.143411] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.391260] SELinux: Disabled at runtime. [ 11.447371] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.454099] systemd[1]: Detected virtualization kvm. [ 11.455636] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.934506] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.937838] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.942530] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.947190] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.950370] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.961217] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.968565] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... [ OK ] Listening on initctl Compatibility Named Pipe. [ 12.011340] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice User and Session Slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Huge Pages File System... [ OK ] Reached target Slices. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Paths. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 12.547172] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 12.945811] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.961928] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.164742] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.188965] EDAC sbridge: Ver: 1.1.2 [ 14.416515] Key type dns_resolver registered [ 14.766454] NFS: Registering the id_resolver key type [ 14.768049] Key type id_resolver registered [ 14.769269] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. 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 irqbalance daemon. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg458-client login: [ 47.880742] libcfs: loading out-of-tree module taints kernel. [ 48.063507] Key type ._llcrypt registered [ 48.068474] Key type .llcrypt registered [ 48.645830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 48.664155] alg: No test for adler32 (adler32-zlib) [ 50.158985] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 50.934288] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 52.719168] Key type lgssc registered [ 54.425733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 212.067633] Lustre: Mounted lustre-client [ 217.333372] hrtimer: interrupt took 5507085 ns [ 219.464606] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 237.537308] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 22s idle [ 239.502824] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing check_logdir /tmp/testlogs/ [ 243.860250] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing yml_node [ 248.513851] Lustre: DEBUG MARKER: Client: 2.17.50.3 [ 250.812958] Lustre: DEBUG MARKER: MDS: 2.17.50.3 [ 253.307880] Lustre: DEBUG MARKER: OSS: 2.17.50.3 [ 254.649915] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Jan 22 05:49:03 EST 2026 [ 271.801399] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 273.492825] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 275.076794] Lustre: DEBUG MARKER: === sanityn: start setup 05:49:24 (1769078964) === [ 275.660539] Lustre: Mounted lustre-client [ 279.464901] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing check_config_client /mnt/lustre [ 297.835781] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 311.168396] Lustre: DEBUG MARKER: === sanityn: finish setup 05:50:00 (1769079000) === [ 313.201348] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 05:50:02 (1769079002) [ 321.169426] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 05:50:10 (1769079010) [ 328.026785] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 05:50:17 (1769079017) [ 334.715796] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 05:50:23 (1769079023) [ 342.015854] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 05:50:30 (1769079030) [ 348.879606] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 05:50:37 (1769079037) [ 354.755366] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 05:50:44 (1769079044) [ 360.461146] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 05:50:49 (1769079049) [ 368.227118] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 05:50:56 (1769079056) [ 376.873730] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 05:51:05 (1769079065) [ 385.041503] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 05:51:13 (1769079073) [ 393.270204] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 05:51:21 (1769079081) [ 393.696296] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 25s idle [ 393.702675] Lustre: Skipped 1 previous similar message [ 399.582818] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 05:51:28 (1769079088) [ 406.599951] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 05:51:35 (1769079095) [ 409.057566] Lustre: lustre-OST0000-osc-ffff9238c60de800: disconnect after 23s idle [ 412.957228] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 05:51:41 (1769079101) [ 419.171375] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 05:51:48 (1769079108) [ 426.754424] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 05:51:55 (1769079115) [ 433.364173] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 05:52:02 (1769079122) [ 441.620702] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 05:52:10 (1769079130) [ 447.608595] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 05:52:16 (1769079136) [ 454.723711] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 05:52:23 (1769079143) [ 455.353696] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 595.891893] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 05:54:44 (1769079284) [ 604.733995] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 05:54:53 (1769079293) [ 611.855705] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 05:55:00 (1769079300) [ 618.624902] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 05:55:07 (1769079307) [ 625.200449] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 05:55:14 (1769079314) [ 631.464983] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 05:55:20 (1769079320) [ 633.516746] Lustre: DEBUG MARKER: chmod [ 639.410487] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 05:55:28 (1769079328) [ 1395.355809] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 06:08:04 (1769080084) [ 1397.216225] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 20s idle [ 1504.736398] Lustre: lustre-OST0000-osc-ffff9238c60de800: disconnect after 20s idle [ 1504.746443] Lustre: Skipped 3 previous similar messages [ 1561.256292] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 06:10:50 (1769080250) [ 1647.133948] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 06:12:16 (1769080336) [ 1756.936399] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 06:14:06 (1769080446) [ 1784.409034] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 06:14:33 (1769080473) [ 1789.700845] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 06:14:39 (1769080479) [ 1790.579677] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1790.642711] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1790.709716] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1790.792136] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1790.855944] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1790.922626] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.006426] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.063231] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.107798] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.158542] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.207252] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.241208] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.301038] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.372456] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.442185] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.488951] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.540987] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.604168] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.674231] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.731234] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.785694] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.815373] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.850826] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.911783] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1791.978407] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.044850] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.115468] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.152569] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.196734] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.231348] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.279809] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.324418] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.396217] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.464967] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.530875] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.602131] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.654297] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.696222] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.752796] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.786512] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.830545] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.892058] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1792.950275] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.007387] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.080931] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.123937] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.178320] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.241673] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.306830] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.347722] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.408407] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.463790] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.498587] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.557978] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.647873] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.717665] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.767776] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.822801] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.870578] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.908128] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.947627] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1793.987377] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.040624] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.090149] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.128351] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.179252] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.222426] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.278879] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.337816] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.383615] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.423108] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.464108] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.503754] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.552626] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.600827] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.638449] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.682819] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.746729] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.793541] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.833064] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.873032] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.922550] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1794.985247] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.039221] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.089425] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.136416] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.172924] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.235416] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.281790] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.325455] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.366844] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.413181] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.452116] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.494291] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.533475] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.572311] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.608201] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.640317] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.673953] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.709667] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.748644] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.787362] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.837237] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.886967] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.934143] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1795.987615] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.024947] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.079127] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.119234] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.159478] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.201561] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.238976] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.277427] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.316716] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.357403] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.410679] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.447873] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.489111] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.537866] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.589513] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.623671] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.665315] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.717739] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.766944] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.810441] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.858478] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.902228] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1796.950905] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.000926] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.054177] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.101404] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.141837] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.174469] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.229959] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.267436] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.304479] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.340429] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.376073] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.414242] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.449603] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.493811] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.542475] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.594569] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.640324] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.706830] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.765110] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.807421] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.860776] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.923332] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1797.972923] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.013508] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.049869] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.084553] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.128076] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.172530] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.210903] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.247400] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.286844] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.329311] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.377263] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.432486] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.481234] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.520794] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.578848] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.617449] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.667749] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.712700] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.766950] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.825687] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.889108] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.933062] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1798.985499] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.038944] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.087347] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.152964] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.216277] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.273747] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.340880] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.389791] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.441436] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.508786] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.552642] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.602700] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.657219] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.710868] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.763442] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.820468] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.891662] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1799.950713] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.005237] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.051730] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.093417] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.155424] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.257720] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.323326] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.415541] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.481354] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.545160] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.589109] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.640313] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.694880] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.745380] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.825414] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.885423] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1800.964051] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.023545] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.083733] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.148704] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.194745] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.252786] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.306758] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.357297] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.411333] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.474419] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.507854] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.544926] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.577467] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.617784] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.668662] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.753821] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.813728] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.866155] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.923443] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1801.965806] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.023489] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.095713] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.176537] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.240424] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.299805] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.363444] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.447267] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.525899] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.579197] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.645228] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.705563] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.781915] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.847293] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.929040] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1802.999264] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.056801] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.120452] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.209951] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.307105] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.371041] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.426178] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.476276] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.547706] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.603806] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.666363] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.764477] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.819076] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1803.910672] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.001648] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.069235] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.131140] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.203902] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.276428] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.350975] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.414764] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.471358] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.523593] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.575819] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.633918] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.695529] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.775515] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.843575] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.920965] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1804.982882] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.058241] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.121537] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.192140] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.265054] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.322758] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.376629] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.436673] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.500103] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.553030] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.611399] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.662639] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.747260] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.831664] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1805.926333] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.009623] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.082722] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.137862] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.212848] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.266986] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.312956] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.362935] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.417886] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.483975] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.526572] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.589935] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.651321] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.717387] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.771543] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.815797] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 20s idle [ 1806.836274] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.890618] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.950583] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1806.988425] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.047442] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.101544] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.154578] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.201736] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.257864] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.322388] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.370595] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.412182] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.466207] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.534648] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.605969] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.662776] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.715253] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.767362] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.811369] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.852405] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.901609] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1807.954927] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.038633] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.120551] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.180365] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.244350] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.296235] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.370421] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.433295] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.486583] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.544269] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.585678] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.640454] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.720789] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.786621] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.854110] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.915488] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1808.994193] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.081729] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.137560] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.196549] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.269201] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.343485] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.404492] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.453953] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.524865] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.579696] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.638197] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.701759] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.755959] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.802252] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.846937] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1809.890490] rw_seq_cst_vs_d (32388): drop_caches: 3 [ 1815.378275] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 06:15:04 (1769080504) [ 1815.783621] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1815.815861] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1815.959756] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.093099] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.327322] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.393476] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.597875] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.643833] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.703391] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1816.957446] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.096292] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.247201] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.316237] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.430507] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.519940] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.696050] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.802031] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1817.848183] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.034276] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.055674] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.301779] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.420873] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.614328] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.714217] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.754165] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.850798] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.880582] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.916771] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1818.964886] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.204328] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.261568] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.324928] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.704425] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.824630] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1819.935970] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.177233] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.263375] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.316748] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.551642] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.689678] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.820300] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1820.952918] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.025305] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.088898] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.182235] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.279696] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.380962] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.425351] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.523225] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.646423] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.825621] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1821.901216] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.018840] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.049211] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.094408] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.236739] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.297310] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.352663] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.640554] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.701104] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.785753] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.876823] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1822.967989] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.072644] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.226531] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.271657] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.544210] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.599873] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.632329] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.702846] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.737969] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.884251] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1823.922964] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.032061] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.109681] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.263977] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.403929] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.501120] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.785864] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1824.870265] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.045329] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.084836] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.186693] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.275810] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.308215] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.369536] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.430991] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.543830] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.702884] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.740753] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.859321] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.904823] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1825.977973] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.133132] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.196333] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.343128] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.384621] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.497242] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.522660] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.598141] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.795940] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.846428] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.927743] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1826.980688] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.047971] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.139638] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.198769] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.320825] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.364087] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.558488] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.701451] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.765795] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.847675] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1827.926906] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.021269] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.058267] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.208234] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.252924] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.297204] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.423650] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.508814] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.684430] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.737417] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.844893] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1828.900650] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.036935] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.191709] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.298656] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.339833] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.533638] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.570799] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1829.801108] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.013877] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.053820] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.158231] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.264765] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.303573] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.421830] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.598746] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.638679] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.799424] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1830.882886] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.101541] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.170904] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.268740] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.392734] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.594815] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.714594] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.882802] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1831.951428] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.018519] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.166658] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.252515] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.338082] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.447817] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.775712] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.889923] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1832.988189] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.017769] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.113800] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.246255] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.304507] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.494385] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.591426] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.720460] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.826678] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.891599] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1833.938140] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.021792] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.117519] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.242041] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.411803] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.488526] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.560862] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.669975] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.735287] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1834.816661] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.010714] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.099289] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.205596] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.369886] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.429973] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1835.536141] rw_seq_cst_vs_d (32973): drop_caches: 3 [ 1837.535995] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 22s idle [ 1837.542403] Lustre: Skipped 1 previous similar message [ 1841.113819] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 06:15:30 (1769080530) [ 1845.934978] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 06:15:35 (1769080535) [ 1850.596355] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 06:15:40 (1769080540) [ 1870.864576] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 06:16:00 (1769080560) [ 1871.078044] bash (35447): drop_caches: 3 [ 1874.218119] bash (35447): drop_caches: 3 [ 1877.376381] bash (35447): drop_caches: 3 [ 1880.511791] bash (35447): drop_caches: 3 [ 1883.638927] bash (35447): drop_caches: 3 [ 1886.766742] bash (35447): drop_caches: 3 [ 1890.049477] bash (35447): drop_caches: 3 [ 1893.176567] bash (35447): drop_caches: 3 [ 1896.294648] bash (35447): drop_caches: 3 [ 1900.392586] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 06:16:29 (1769080589) [ 1907.056903] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 06:16:36 (1769080596) [ 1931.530128] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 06:17:01 (1769080621) [ 1937.996890] Lustre: DEBUG MARKER: loop 5 [ 1941.678123] Lustre: DEBUG MARKER: loop 10 [ 1945.056159] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 21s idle [ 1945.066410] Lustre: Skipped 1 previous similar message [ 1945.372882] Lustre: DEBUG MARKER: loop 15 [ 1948.796678] Lustre: DEBUG MARKER: loop 20 [ 1954.453041] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 06:17:23 (1769080643) [ 1959.111517] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 06:17:28 (1769080648) [ 1963.724257] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 06:17:33 (1769080653) [ 1975.776101] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 21s idle [ 1975.779978] Lustre: Skipped 1 previous similar message [ 2030.091131] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 06:18:39 (1769080719) [ 2034.047656] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 06:18:43 (1769080723) [ 2037.492343] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 06:18:47 (1769080727) [ 2042.394367] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 06:18:51 (1769080731) [ 2047.812334] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 06:18:57 (1769080737) [ 2052.787311] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 06:19:02 (1769080742) [ 2058.513090] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 06:19:08 (1769080748) [ 2063.514419] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2064.544661] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 06:19:14 (1769080754) [ 2070.604388] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 06:19:20 (1769080760) [ 2070.925364] Lustre: *** cfs_fail_loc=314, val=0*** [ 2071.967600] Lustre: *** cfs_fail_loc=314, val=0*** [ 2071.972691] Lustre: Skipped 2 previous similar messages [ 2076.349335] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 06:19:25 (1769080765) [ 2083.352918] Lustre: *** cfs_fail_loc=314, val=0*** [ 2083.398191] LustreError: lustre-OST0000-osc-ffff9238c60de800: operation ldlm_enqueue to node 192.168.204.158@tcp failed: rc = -107 [ 2083.402979] Lustre: lustre-OST0000-osc-ffff9238c60de800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2083.414049] LustreError: lustre-OST0000-osc-ffff9238c60de800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2083.421545] LustreError: 46349:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9238c60de800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff9238e0f0f700) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2083.428784] Lustre: lustre-OST0000-osc-ffff9238c60de800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2088.063474] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 06:19:37 (1769080777) [ 2088.400114] LustreError: 46940:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2091.431623] LustreError: 46940:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2095.860193] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 06:19:45 (1769080785) [ 2102.397774] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 06:19:51 (1769080791) [ 2107.640857] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2108.764354] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 06:19:58 (1769080798) [ 2109.951603] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2111.139572] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 06:20:00 (1769080800) [ 2114.020237] Lustre: lustre-MDT0000-mdc-ffff9238c410f800: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2119.142684] LustreError: MGC192.168.204.158@tcp: Connection to MGS (at 192.168.204.158@tcp) was lost; in progress operations using this service will fail [ 2119.161638] Lustre: Evicted from MGS (at 192.168.204.158@tcp) after server handle changed from 0x9f6c200133475349 to 0x9f6c20013352e80f [ 2119.168389] Lustre: MGC192.168.204.158@tcp: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2123.947533] Lustre: lustre-MDT0000-mdc-ffff9238c410f800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2124.256164] Lustre: lustre-OST0000-osc-ffff9238c60de800: disconnect after 20s idle [ 2124.264522] Lustre: Skipped 3 previous similar messages [ 2139.708655] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 06:20:29 (1769080829) [ 2167.833305] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 06:20:57 (1769080857) [ 2179.858749] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 06:21:09 (1769080869) [ 2231.786616] Lustre: lustre-OST0000-osc-ffff9238c410f800: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2231.795560] Lustre: Skipped 1 previous similar message [ 2231.810672] LustreError: lustre-OST0000-osc-ffff9238c410f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2231.824578] LustreError: lustre-OST0000-osc-ffff9238c60de800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2231.826552] Lustre: lustre-OST0000-osc-ffff9238c410f800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2231.837562] Lustre: Skipped 2 previous similar messages [ 2246.097402] Lustre: lustre-OST0001-osc-ffff9238c410f800: Connection to lustre-OST0001 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2246.110122] Lustre: Skipped 1 previous similar message [ 2246.117885] LustreError: lustre-OST0001-osc-ffff9238c410f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2246.135513] Lustre: lustre-OST0001-osc-ffff9238c410f800: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 2252.255200] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 20s idle [ 2252.258422] Lustre: Skipped 1 previous similar message [ 2259.247835] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid 50 [ 2260.024143] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid in IDLE state after 0 sec [ 2261.927379] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid 50 [ 2262.718649] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid in FULL state after 0 sec [ 2265.336640] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid 50 [ 2266.062832] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid in IDLE state after 0 sec [ 2267.919478] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid 50 [ 2268.838411] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid in IDLE state after 0 sec [ 2274.531619] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid 50 [ 2275.475555] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9238c410f800.ost_server_uuid in IDLE state after 0 sec [ 2277.740115] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid 50 [ 2278.680576] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9238c410f800.ost_server_uuid in IDLE state after 0 sec [ 2279.892962] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 06:22:49 (1769080969) [ 2281.786928] Lustre: DEBUG MARKER: Race attempt 0 [ 2284.121334] Lustre: DEBUG MARKER: Wait for 57397 57426 for 60 sec... [ 2348.100930] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 06:23:57 (1769081037) [ 2354.006759] Lustre: DEBUG MARKER: start test - cycle (0) [ 2374.292350] Lustre: DEBUG MARKER: start test - cycle (1) [ 2398.159698] Lustre: DEBUG MARKER: start test - cycle (2) [ 2419.097858] Lustre: DEBUG MARKER: start test - cycle (3) [ 2438.584057] Lustre: DEBUG MARKER: start test - cycle (4) [ 2461.066533] Lustre: DEBUG MARKER: start test - cycle (5) [ 2486.220919] Lustre: DEBUG MARKER: start test - cycle (6) [ 2487.775437] Lustre: lustre-OST0000-osc-ffff9238c60de800: disconnect after 21s idle [ 2487.782612] Lustre: Skipped 2 previous similar messages [ 2511.764595] Lustre: DEBUG MARKER: start test - cycle (7) [ 2536.209683] Lustre: DEBUG MARKER: start test - cycle (8) [ 2556.787605] Lustre: DEBUG MARKER: start test - cycle (9) [ 2576.241905] Lustre: DEBUG MARKER: start test - cycle (10) [ 2604.481266] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 06:28:13 (1769081293) [ 2620.899769] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 22s idle [ 2620.903833] Lustre: Skipped 2 previous similar messages [ 2663.970423] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 06:29:13 (1769081353) [ 2670.261756] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 06:29:19 (1769081359) [ 2678.769489] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 06:29:27 (1769081367) [ 2687.484881] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 06:29:36 (1769081376) [ 2688.030373] Lustre: *** cfs_fail_loc=411, val=0*** [ 2694.073929] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2695.713161] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 06:29:44 (1769081384) [ 2711.851869] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 06:30:01 (1769081401) [ 2725.712560] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 06:30:15 (1769081415) [ 2739.674863] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 06:30:28 (1769081428) [ 2755.433796] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 06:30:44 (1769081444) [ 2766.767378] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 06:30:56 (1769081456) [ 2777.583510] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 06:31:06 (1769081466) [ 2787.535655] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 06:31:16 (1769081476) [ 2797.281275] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 06:31:26 (1769081486) [ 2808.089889] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 06:31:37 (1769081497) [ 2818.588308] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 06:31:47 (1769081507) [ 2829.669531] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 06:31:59 (1769081519) [ 2840.978224] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 06:32:10 (1769081530) [ 3460.578183] Lustre: lustre-OST0000-osc-ffff9238c60de800: disconnect after 22s idle [ 3460.581960] Lustre: Skipped 8 previous similar messages [ 3644.820777] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 06:45:34 (1769082334) [ 3650.585031] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 06:45:40 (1769082340) [ 3656.379470] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 06:45:46 (1769082346) [ 3661.918961] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 06:45:51 (1769082351) [ 3667.144819] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 06:45:57 (1769082357) [ 3672.356890] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 06:46:02 (1769082362) [ 3677.572419] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 06:46:07 (1769082367) [ 3683.354421] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 06:46:13 (1769082373) [ 3688.737131] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 06:46:18 (1769082378) [ 3716.476396] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 06:46:46 (1769082406) [ 3721.823412] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 06:46:51 (1769082411) [ 3727.358619] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 06:46:57 (1769082417) [ 3732.701548] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 06:47:02 (1769082422) [ 3738.274660] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 06:47:08 (1769082428) [ 3743.818208] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 06:47:13 (1769082433) [ 3749.306729] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 06:47:19 (1769082439) [ 3755.033906] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 06:47:24 (1769082444) [ 3760.913024] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 06:47:30 (1769082450) [ 3801.966388] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 06:48:11 (1769082491) [ 4234.713935] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 06:55:24 (1769082924) [ 4239.950104] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 06:55:29 (1769082929) [ 4245.280335] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 06:55:35 (1769082935) [ 4250.647930] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 06:55:40 (1769082940) [ 4255.861402] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 06:55:45 (1769082945) [ 4261.100506] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 06:55:51 (1769082951) [ 4266.376087] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 06:55:56 (1769082956) [ 4271.742382] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 06:56:01 (1769082961) [ 4277.075227] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 06:56:07 (1769082967) [ 4283.117135] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 06:56:12 (1769082972) [ 4300.255138] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 20s idle [ 4300.257811] Lustre: Skipped 5 previous similar messages [ 4321.502092] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 06:56:51 (1769083011) [ 4327.522589] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 06:56:57 (1769083017) [ 4334.055207] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 06:57:03 (1769083023) [ 4339.096416] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 06:57:09 (1769083029) [ 4344.259639] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 06:57:14 (1769083034) [ 4349.461094] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 06:57:19 (1769083039) [ 4354.564500] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 06:57:24 (1769083044) [ 4359.142310] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 06:57:29 (1769083049) [ 4364.685837] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 06:57:34 (1769083054) [ 4831.171793] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 07:05:21 (1769083521) [ 4836.283224] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 07:05:26 (1769083526) [ 4841.392182] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 07:05:31 (1769083531) [ 4846.675455] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 07:05:36 (1769083536) [ 4851.706266] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 07:05:41 (1769083541) [ 4856.641663] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 07:05:46 (1769083546) [ 4861.817195] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 07:05:51 (1769083551) [ 4866.794312] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 07:05:56 (1769083556) [ 4871.787093] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 07:06:01 (1769083561) [ 4876.779541] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 07:06:06 (1769083566) [ 4881.692449] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 07:06:11 (1769083571) [ 4891.245568] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 07:06:20 (1769083580) [ 4901.656909] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 07:06:31 (1769083591) [ 4911.552640] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 07:06:41 (1769083601) [ 4919.780019] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 21s idle [ 4919.785429] Lustre: Skipped 2 previous similar messages [ 4921.141900] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 07:06:50 (1769083610) [ 4930.983275] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 07:07:00 (1769083620) [ 4948.475563] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 07:07:16 (1769083636) [ 4949.034162] LustreError: 6147:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4951.127192] LustreError: 6147:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4960.194906] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 07:07:29 (1769083649) [ 4966.878629] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 07:07:36 (1769083656) [ 4967.163451] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4971.231117] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4971.253277] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4975.311126] LustreError: 237962:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4975.345076] LustreError: 237968:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4979.407128] LustreError: 237968:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4983.491672] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 07:07:52 (1769083672) [ 4992.001317] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 07:08:01 (1769083681) [ 4996.920961] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 07:08:06 (1769083686) [ 5002.449967] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 07:08:11 (1769083691) [ 5030.081780] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 07:08:39 (1769083719) [ 5039.502144] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 07:08:49 (1769083729) [ 5049.066294] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 07:08:58 (1769083738) [ 5063.837451] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 07:09:13 (1769083753) [ 5075.143853] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 07:09:24 (1769083764) [ 5089.934411] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 07:09:39 (1769083779) [ 5105.832779] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 07:09:55 (1769083795) [ 5123.019947] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 07:10:12 (1769083812) [ 5134.005653] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 07:10:23 (1769083823) [ 5180.498976] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 07:11:09 (1769083869) [ 5185.310755] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5190.750760] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 07:11:20 (1769083880) [ 5196.125820] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 07:11:25 (1769083885) [ 5201.513825] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 07:11:30 (1769083890) [ 5206.924682] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 07:11:36 (1769083896) [ 5234.134414] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 07:12:03 (1769083923) [ 5270.574647] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 07:12:40 (1769083960) [ 5275.361275] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 07:12:44 (1769083964) [ 5280.628839] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 07:12:49 (1769083969) [ 5283.863534] LustreError: lustre-MDT0000-mdc-ffff9238c60de800: operation ldlm_enqueue to node 192.168.204.158@tcp failed: rc = -35 [ 5288.722312] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 07:12:58 (1769083978) [ 5289.148771] LustreError: 2394:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5291.239131] LustreError: 2394:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5298.183977] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 07:13:07 (1769083987) [ 5395.494357] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 07:14:45 (1769084085) [ 5400.704731] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 07:14:50 (1769084090) [ 5408.410915] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 07:14:58 (1769084098) [ 5417.425439] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 07:15:06 (1769084106) [ 5427.007687] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 07:15:16 (1769084116) [ 5439.231989] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 07:15:28 (1769084128) [ 5452.101387] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 07:15:41 (1769084141) [ 5458.541895] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 07:15:48 (1769084148) [ 5464.473224] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 07:15:54 (1769084154) [ 5476.996617] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 07:16:06 (1769084166) [ 5523.935689] Lustre: lustre-OST0000-osc-ffff9238c410f800: disconnect after 20s idle [ 5523.938372] Lustre: Skipped 10 previous similar messages [ 5525.712992] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 07:16:55 (1769084215) [ 5642.430961] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 07:18:52 (1769084332) [ 5761.527262] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 07:20:51 (1769084451) [ 6041.888786] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 07:25:31 (1769084731) [ 6073.939465] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 07:26:03 (1769084763) [ 6103.926124] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 07:26:33 (1769084793) [ 6124.886325] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 07:26:54 (1769084814) [ 6153.695201] Lustre: lustre-OST0001-osc-ffff9238c410f800: disconnect after 21s idle [ 6153.697694] Lustre: Skipped 12 previous similar messages [ 6185.915783] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 07:27:55 (1769084875) [ 6239.542924] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 07:28:49 (1769084929) [ 6329.091581] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 07:30:19 (1769085019) [ 6330.211760] LustreError: 284849:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c410f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6330.217405] LustreError: 284849:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6330.241192] Lustre: Unmounted lustre-client [ 6330.935611] LustreError: 284862:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c60de800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6330.940501] LustreError: 284862:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6330.943696] LustreError: 284862:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6330.946578] LustreError: 284862:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6330.967809] Lustre: Unmounted lustre-client [ 6389.330868] Lustre: Mounted lustre-client [ 6390.816657] Lustre: Mounted lustre-client [ 6391.822349] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6455.942380] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 07:32:25 (1769085145) [ 6463.703526] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 07:32:33 (1769085153) [ 6467.137031] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 07:32:37 (1769085157) [ 6517.367545] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 07:33:27 (1769085207) [ 6567.535786] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 07:34:17 (1769085257) [ 6571.591987] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 07:34:21 (1769085261) [ 6608.665815] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 07:34:58 (1769085298) [ 6620.076370] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 07:35:09 (1769085309) [ 6662.537237] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 07:35:52 (1769085352) [ 6665.771706] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 07:35:55 (1769085355) [ 6678.542248] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 07:36:08 (1769085368) [ 6682.283989] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 07:36:12 (1769085372) [ 6682.808484] LustreError: 306169:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3f3:0x0]: rc = -5 [ 6682.812650] LustreError: 306169:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6683.317272] LustreError: 306389:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3fa:0x0]: rc = -5 [ 6683.320083] LustreError: 306389:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 6 previous similar messages [ 6683.323789] LustreError: 306389:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6683.327614] LustreError: 306389:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 6 previous similar messages [ 6684.379022] LustreError: 306481:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x413:0x0]: rc = -5 [ 6684.382988] LustreError: 306481:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 17 previous similar messages [ 6684.386484] LustreError: 306481:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6684.390474] LustreError: 306481:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 17 previous similar messages [ 6686.395510] LustreError: 306648:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x450:0x0]: rc = -5 [ 6686.399720] LustreError: 306648:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 31 previous similar messages [ 6686.402585] LustreError: 306648:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6686.405199] LustreError: 306648:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 31 previous similar messages [ 6690.433418] LustreError: 307009:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x4ce:0x0]: rc = -5 [ 6690.437990] LustreError: 307009:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 67 previous similar messages [ 6690.441295] LustreError: 307009:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6690.444270] LustreError: 307009:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 67 previous similar messages [ 6698.543691] LustreError: 307662:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x5b4:0x0]: rc = -5 [ 6698.548245] LustreError: 307662:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 151 previous similar messages [ 6698.551965] LustreError: 307662:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6698.555245] LustreError: 307662:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 151 previous similar messages [ 6714.573736] LustreError: 309143:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x77d:0x0]: rc = -5 [ 6714.578495] LustreError: 309143:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 563 previous similar messages [ 6714.581788] LustreError: 309143:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6714.584525] LustreError: 309143:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 563 previous similar messages [ 6743.818839] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 07:37:13 (1769085433) [ 6746.064023] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 07:37:16 (1769085436) [ 6769.631153] Lustre: lustre-OST0001-osc-ffff9238d19c6800: disconnect after 21s idle [ 6769.634721] Lustre: Skipped 8 previous similar messages [ 6788.815964] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 07:37:58 (1769085478) [ 6789.303541] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6789.836677] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 07:37:59 (1769085479) [ 6827.951765] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 07:38:37 (1769085517) [ 6830.129259] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 07:38:40 (1769085520) [ 6952.316088] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 07:40:42 (1769085642) [ 6959.549278] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 07:40:49 (1769085649) [ 6962.126716] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 07:40:52 (1769085652) [ 7144.313458] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 07:43:54 (1769085834) [ 7326.664955] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 07:46:56 (1769086016) [ 7328.823190] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 07:46:58 (1769086018) [ 7337.793518] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 07:47:07 (1769086027) [ 7337.842911] Lustre: DEBUG MARKER: write [ 7337.864869] LustreError: 287256:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7339.870547] Lustre: DEBUG MARKER: kill 376648 [ 7339.872555] LustreError: 376648:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7342.967121] LustreError: 287256:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7345.911125] LustreError: 376648:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7348.074376] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 07:47:18 (1769086038) [ 7350.259144] LustreError: 377261:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7360.351094] LustreError: 377261:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7362.564195] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 07:47:32 (1769086052) [ 7362.663642] LustreError: 377849:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7364.743065] LustreError: 377849:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [ 7370.721053] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 07:47:40 (1769086060) [ 7371.219471] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7371.760977] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 07:47:41 (1769086061) [ 7374.218229] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 07:47:44 (1769086064) [ 7376.417065] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 07:47:46 (1769086066) [ 7378.607049] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 07:47:48 (1769086068) [ 7380.781501] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 07:47:50 (1769086070) [ 7383.019481] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 07:47:52 (1769086072) [ 7385.515216] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 07:47:55 (1769086075) [ 7388.719242] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 07:47:58 (1769086078) [ 7391.491211] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 07:48:01 (1769086081) [ 7392.123457] Lustre: *** cfs_fail_loc=415, val=0*** [ 7398.831685] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 07:48:08 (1769086088) [ 7417.841868] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 07:48:27 (1769086107) [ 7417.930870] LustreError: 287257:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7417.933398] LustreError: 287257:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7423.023071] LustreError: 286570:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7423.026776] LustreError: 286570:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 7433.223069] LustreError: 286569:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7433.226015] LustreError: 286569:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 6 previous similar messages [ 7435.511949] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 07:48:45 (1769086125) [ 7438.095838] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 07:48:48 (1769086128) [ 7440.585354] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 07:48:50 (1769086130) [ 7442.941704] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 07:48:52 (1769086132) [ 7447.278891] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 07:48:57 (1769086137) [ 7455.745762] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 07:49:05 (1769086145) [ 7455.920252] LustreError: 388577:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7455.923105] LustreError: 388577:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [ 7459.983094] LustreError: 388577:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7459.985877] LustreError: 388577:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [ 7462.182150] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 07:49:12 (1769086152) [ 7463.411431] LustreError: 389288:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238f42a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7463.415862] LustreError: 389288:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7463.421836] LustreError: 389288:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7463.423902] LustreError: 389288:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 7463.444195] Lustre: Unmounted lustre-client [ 7463.979089] LustreError: 389308:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d19c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7463.982496] LustreError: 389308:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7463.987915] LustreError: 389308:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7463.990101] LustreError: 389308:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7464.048887] Lustre: Unmounted lustre-client [ 7464.551755] Lustre: DEBUG MARKER: Iteration 1 [ 7464.657938] LustreError: 389471:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7464.658141] LustreError: 389472:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7464.662633] LustreError: 389471:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7464.702674] Lustre: Mounted lustre-client [ 7465.195263] LustreError: 389580:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c3388000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7465.200289] LustreError: 389580:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 7465.200376] LustreError: 389579:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7465.205156] LustreError: 389579:0:(obd_class.h:479:obd_check_dev()) Skipped 11 previous similar messages [ 7465.253118] Lustre: Unmounted lustre-client [ 7466.225959] Key type lgssc unregistered [ 7466.344357] LNet: 389822:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7466.346827] LNetError: 389822:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7466.355513] LNet: Removed LNI 192.168.204.58@tcp [ 7466.675124] Key type .llcrypt unregistered [ 7466.676384] Key type ._llcrypt unregistered [ 7467.027489] Key type ._llcrypt registered [ 7467.029049] Key type .llcrypt registered [ 7467.282686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7467.288156] alg: No test for adler32 (adler32-zlib) [ 7468.258266] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7468.553324] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7470.175217] Key type lgssc registered [ 7470.777204] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7474.954669] Lustre: DEBUG MARKER: Iteration 2 [ 7475.049556] LustreError: 390650:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7475.049806] LustreError: 390651:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7475.054738] LustreError: 390650:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7476.106704] Lustre: Mounted lustre-client [ 7476.108027] Lustre: Skipped 1 previous similar message [ 7476.536248] LustreError: 390766:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c301d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7476.540051] LustreError: 390767:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7476.541250] LustreError: 390766:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7476.591408] Lustre: Unmounted lustre-client [ 7476.592677] Lustre: Skipped 1 previous similar message [ 7477.504541] Key type lgssc unregistered [ 7477.622486] LNet: 391009:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7477.625057] LNetError: 391009:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7477.631805] LNet: Removed LNI 192.168.204.58@tcp [ 7477.856075] Key type .llcrypt unregistered [ 7477.857251] Key type ._llcrypt unregistered [ 7478.143364] Key type ._llcrypt registered [ 7478.144375] Key type .llcrypt registered [ 7478.334756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7478.339434] alg: No test for adler32 (adler32-zlib) [ 7479.203950] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7479.288623] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7480.871217] Key type lgssc registered [ 7481.285688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7485.243636] Lustre: DEBUG MARKER: Iteration 3 [ 7485.369431] LustreError: 391836:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7485.370103] LustreError: 391838:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7485.377328] LustreError: 391836:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7486.432320] Lustre: Mounted lustre-client [ 7486.883993] LustreError: 391950:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d1d5e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7486.887577] LustreError: 391950:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7486.887813] LustreError: 391951:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7486.957105] Lustre: Unmounted lustre-client [ 7487.899364] Key type lgssc unregistered [ 7488.033468] LNet: 392194:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7488.036891] LNetError: 392194:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7488.046473] LNet: Removed LNI 192.168.204.58@tcp [ 7488.285131] Key type .llcrypt unregistered [ 7488.286577] Key type ._llcrypt unregistered [ 7488.579487] Key type ._llcrypt registered [ 7488.580496] Key type .llcrypt registered [ 7488.751744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7488.756586] alg: No test for adler32 (adler32-zlib) [ 7489.604060] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7489.682845] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7491.263141] Key type lgssc registered [ 7491.626566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7495.402920] Lustre: DEBUG MARKER: Iteration 4 [ 7495.512043] LustreError: 393022:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7495.512097] LustreError: 393023:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7495.516529] LustreError: 393022:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7496.576422] Lustre: Mounted lustre-client [ 7496.578190] Lustre: Skipped 1 previous similar message [ 7497.077034] LustreError: 393129:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0fa5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7497.079378] LustreError: 393130:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7497.081298] LustreError: 393129:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7497.122278] Lustre: Unmounted lustre-client [ 7498.133418] Key type lgssc unregistered [ 7498.248399] LNet: 393373:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7498.250725] LNetError: 393373:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7498.260761] LNet: Removed LNI 192.168.204.58@tcp [ 7498.495104] Key type .llcrypt unregistered [ 7498.496346] Key type ._llcrypt unregistered [ 7498.729176] Key type ._llcrypt registered [ 7498.730544] Key type .llcrypt registered [ 7498.905395] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7498.912477] alg: No test for adler32 (adler32-zlib) [ 7499.775883] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7499.858034] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7501.439166] Key type lgssc registered [ 7501.828423] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7505.507133] Lustre: DEBUG MARKER: Iteration 5 [ 7505.611265] LustreError: 394200:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7505.611291] LustreError: 394201:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7505.615721] LustreError: 394200:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7506.668072] Lustre: Mounted lustre-client [ 7506.669628] Lustre: Skipped 1 previous similar message [ 7507.124210] LustreError: 394311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c3bef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7507.130514] LustreError: 394311:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7507.133607] LustreError: 394311:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7507.170063] Lustre: Unmounted lustre-client [ 7508.135544] Key type lgssc unregistered [ 7508.260445] LNet: 394554:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7508.262609] LNetError: 394554:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7508.273858] LNet: Removed LNI 192.168.204.58@tcp [ 7508.524118] Key type .llcrypt unregistered [ 7508.525342] Key type ._llcrypt unregistered [ 7508.816390] Key type ._llcrypt registered [ 7508.818263] Key type .llcrypt registered [ 7508.996598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7509.001930] alg: No test for adler32 (adler32-zlib) [ 7509.877207] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7509.971365] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7511.567142] Key type lgssc registered [ 7511.954156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7515.637304] Lustre: DEBUG MARKER: Iteration 6 [ 7515.736593] LustreError: 395382:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7515.739754] LustreError: 395383:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7515.741863] LustreError: 395382:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7516.795595] Lustre: Mounted lustre-client [ 7516.796832] Lustre: Skipped 1 previous similar message [ 7517.230486] LustreError: 395496:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c4f06800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7517.232949] LustreError: 395498:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7517.234127] LustreError: 395496:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7517.268266] Lustre: Unmounted lustre-client [ 7518.187866] Key type lgssc unregistered [ 7518.297371] LNet: 395739:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7518.300046] LNetError: 395739:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7518.306675] LNet: Removed LNI 192.168.204.58@tcp [ 7518.538109] Key type .llcrypt unregistered [ 7518.539250] Key type ._llcrypt unregistered [ 7518.768910] Key type ._llcrypt registered [ 7518.769895] Key type .llcrypt registered [ 7518.985799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7518.991262] alg: No test for adler32 (adler32-zlib) [ 7519.846371] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7519.927425] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7521.511176] Key type lgssc registered [ 7521.897346] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7525.640896] Lustre: DEBUG MARKER: Iteration 7 [ 7525.738763] LustreError: 396568:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7525.738793] LustreError: 396569:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7525.742755] LustreError: 396568:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7526.806951] Lustre: Mounted lustre-client [ 7526.808366] Lustre: Skipped 1 previous similar message [ 7527.317956] LustreError: 396681:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d1d58800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7527.320109] LustreError: 396682:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7527.321570] LustreError: 396681:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7527.371485] Lustre: Unmounted lustre-client [ 7528.313039] Key type lgssc unregistered [ 7528.428542] LNet: 396925:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7528.431047] LNetError: 396925:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7528.437718] LNet: Removed LNI 192.168.204.58@tcp [ 7528.646089] Key type .llcrypt unregistered [ 7528.647119] Key type ._llcrypt unregistered [ 7528.887500] Key type ._llcrypt registered [ 7528.888675] Key type .llcrypt registered [ 7529.087974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7529.093466] alg: No test for adler32 (adler32-zlib) [ 7529.961201] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7530.040578] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7531.623153] Key type lgssc registered [ 7532.032435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7535.769540] Lustre: DEBUG MARKER: Iteration 8 [ 7535.892866] LustreError: 397753:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7535.892957] LustreError: 397754:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7535.899583] LustreError: 397753:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7536.962126] Lustre: Mounted lustre-client [ 7537.526439] LustreError: 397866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c4f03800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7537.532210] LustreError: 397866:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7537.568085] Lustre: Unmounted lustre-client [ 7538.560406] Key type lgssc unregistered [ 7538.676509] LNet: 398109:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7538.678736] LNetError: 398109:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7538.687764] LNet: Removed LNI 192.168.204.58@tcp [ 7538.922091] Key type .llcrypt unregistered [ 7538.923648] Key type ._llcrypt unregistered [ 7539.182534] Key type ._llcrypt registered [ 7539.183600] Key type .llcrypt registered [ 7539.386562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7539.391809] alg: No test for adler32 (adler32-zlib) [ 7540.239236] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7540.321565] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7541.903219] Key type lgssc registered [ 7542.348521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7545.958144] Lustre: DEBUG MARKER: Iteration 9 [ 7546.085510] LustreError: 398938:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7546.085799] LustreError: 398939:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7546.092840] LustreError: 398938:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7547.158053] Lustre: Mounted lustre-client [ 7547.726510] LustreError: 399051:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d14ae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7547.728347] LustreError: 399052:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7547.729501] LustreError: 399051:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7547.778770] Lustre: Unmounted lustre-client [ 7548.816098] Key type lgssc unregistered [ 7548.937379] LNet: 399293:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7548.939829] LNetError: 399293:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7548.949661] LNet: Removed LNI 192.168.204.58@tcp [ 7549.185110] Key type .llcrypt unregistered [ 7549.186297] Key type ._llcrypt unregistered [ 7549.460264] Key type ._llcrypt registered [ 7549.461450] Key type .llcrypt registered [ 7549.650920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7549.656460] alg: No test for adler32 (adler32-zlib) [ 7550.507081] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7550.592429] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7552.175121] Key type lgssc registered [ 7552.564509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7556.074086] Lustre: DEBUG MARKER: Iteration 10 [ 7556.175560] LustreError: 400122:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7556.175591] LustreError: 400123:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7556.179970] LustreError: 400122:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7557.245659] Lustre: Mounted lustre-client [ 7557.246913] Lustre: Skipped 1 previous similar message [ 7557.718976] LustreError: 400233:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c533e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7557.722092] LustreError: 400234:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7557.722933] LustreError: 400233:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7557.760690] Lustre: Unmounted lustre-client [ 7558.748611] Key type lgssc unregistered [ 7558.874470] LNet: 400474:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7558.877196] LNetError: 400474:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7558.887690] LNet: Removed LNI 192.168.204.58@tcp [ 7559.130129] Key type .llcrypt unregistered [ 7559.131996] Key type ._llcrypt unregistered [ 7559.397734] Key type ._llcrypt registered [ 7559.398969] Key type .llcrypt registered [ 7559.577522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7559.589707] alg: No test for adler32 (adler32-zlib) [ 7560.451639] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7560.538041] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7562.127171] Key type lgssc registered [ 7562.529621] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7565.994294] Lustre: DEBUG MARKER: Iteration 11 [ 7566.096183] LustreError: 401301:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7566.096194] LustreError: 401302:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7566.101740] LustreError: 401301:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7567.159305] Lustre: Mounted lustre-client [ 7567.160836] Lustre: Skipped 1 previous similar message [ 7567.629753] LustreError: 401413:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d15a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7567.631954] LustreError: 401414:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7567.634028] LustreError: 401413:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7567.699728] Lustre: Unmounted lustre-client [ 7568.648914] Key type lgssc unregistered [ 7568.771288] LNet: 401655:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7568.773995] LNetError: 401655:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7568.781714] LNet: Removed LNI 192.168.204.58@tcp [ 7569.050117] Key type .llcrypt unregistered [ 7569.051192] Key type ._llcrypt unregistered [ 7569.295717] Key type ._llcrypt registered [ 7569.297974] Key type .llcrypt registered [ 7569.507362] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7569.512277] alg: No test for adler32 (adler32-zlib) [ 7570.372697] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7570.457652] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7572.039138] Key type lgssc registered [ 7572.456758] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7576.099816] Lustre: DEBUG MARKER: Iteration 12 [ 7576.211026] LustreError: 402484:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7576.211047] LustreError: 402485:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7576.216559] LustreError: 402484:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7577.266818] Lustre: Mounted lustre-client [ 7577.714844] LustreError: 402600:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cb2b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7577.718967] LustreError: 402600:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7577.723706] LustreError: 402599:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7577.727451] LustreError: 402599:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7577.779555] Lustre: Unmounted lustre-client [ 7577.780840] Lustre: Skipped 1 previous similar message [ 7578.751856] Key type lgssc unregistered [ 7578.874509] LNet: 402842:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7578.877264] LNetError: 402842:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7578.887837] LNet: Removed LNI 192.168.204.58@tcp [ 7579.113100] Key type .llcrypt unregistered [ 7579.114308] Key type ._llcrypt unregistered [ 7579.408371] Key type ._llcrypt registered [ 7579.414542] Key type .llcrypt registered [ 7579.599157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7579.604482] alg: No test for adler32 (adler32-zlib) [ 7580.459956] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7580.544908] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7582.135163] Key type lgssc registered [ 7582.523634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7585.908502] Lustre: DEBUG MARKER: Iteration 13 [ 7586.040390] LustreError: 403671:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7586.040441] LustreError: 403672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7586.045261] LustreError: 403671:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7587.093281] Lustre: Mounted lustre-client [ 7587.094979] Lustre: Skipped 1 previous similar message [ 7587.614540] LustreError: 403780:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cb5a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7587.620060] LustreError: 403780:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7587.622575] LustreError: 403780:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7587.646090] Lustre: Unmounted lustre-client [ 7588.659222] Key type lgssc unregistered [ 7588.789803] LNet: 404022:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7588.792430] LNetError: 404022:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7588.804198] LNet: Removed LNI 192.168.204.58@tcp [ 7589.043092] Key type .llcrypt unregistered [ 7589.044194] Key type ._llcrypt unregistered [ 7589.274797] Key type ._llcrypt registered [ 7589.275854] Key type .llcrypt registered [ 7589.491756] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7589.496239] alg: No test for adler32 (adler32-zlib) [ 7590.340137] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7590.418841] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7591.999196] Key type lgssc registered [ 7592.372488] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7595.680932] Lustre: DEBUG MARKER: Iteration 14 [ 7595.779265] LustreError: 404850:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7595.779318] LustreError: 404851:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7595.783907] LustreError: 404850:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7596.843021] Lustre: Mounted lustre-client [ 7596.844020] Lustre: Skipped 1 previous similar message [ 7597.289471] LustreError: 404962:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c3b14000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7597.292664] LustreError: 404962:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7597.293115] LustreError: 404963:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7597.384219] Lustre: Unmounted lustre-client [ 7598.354357] Key type lgssc unregistered [ 7598.479620] LNet: 405204:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7598.483596] LNetError: 405204:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7598.490767] LNet: Removed LNI 192.168.204.58@tcp [ 7598.746115] Key type .llcrypt unregistered [ 7598.747395] Key type ._llcrypt unregistered [ 7599.000393] Key type ._llcrypt registered [ 7599.002274] Key type .llcrypt registered [ 7599.181936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7599.198977] alg: No test for adler32 (adler32-zlib) [ 7600.054120] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7600.142132] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7601.727136] Key type lgssc registered [ 7602.141464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7605.781232] Lustre: DEBUG MARKER: Iteration 15 [ 7605.905621] LustreError: 406033:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7605.905678] LustreError: 406034:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7605.911769] LustreError: 406033:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7606.972493] Lustre: Mounted lustre-client [ 7607.422062] LustreError: 406143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d14aa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7607.426571] LustreError: 406143:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7607.426894] LustreError: 406144:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7607.502781] Lustre: Unmounted lustre-client [ 7607.504029] Lustre: Skipped 1 previous similar message [ 7608.493972] Key type lgssc unregistered [ 7608.607528] LNet: 406385:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7608.609863] LNetError: 406385:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7608.616731] LNet: Removed LNI 192.168.204.58@tcp [ 7608.859094] Key type .llcrypt unregistered [ 7608.860466] Key type ._llcrypt unregistered [ 7609.154441] Key type ._llcrypt registered [ 7609.157266] Key type .llcrypt registered [ 7609.331503] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7609.336476] alg: No test for adler32 (adler32-zlib) [ 7610.205090] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7610.289050] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7611.871194] Key type lgssc registered [ 7612.265510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7616.036700] Lustre: DEBUG MARKER: Iteration 16 [ 7616.138265] LustreError: 407214:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7616.140282] LustreError: 407215:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7616.142894] LustreError: 407214:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7617.206813] Lustre: Mounted lustre-client [ 7617.671478] LustreError: 407327:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238fc0d9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7617.676013] LustreError: 407327:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7617.676573] LustreError: 407326:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7617.757727] Lustre: Unmounted lustre-client [ 7618.724187] Key type lgssc unregistered [ 7618.845418] LNet: 407569:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7618.848511] LNetError: 407569:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7618.855784] LNet: Removed LNI 192.168.204.58@tcp [ 7619.101122] Key type .llcrypt unregistered [ 7619.102782] Key type ._llcrypt unregistered [ 7619.378961] Key type ._llcrypt registered [ 7619.380829] Key type .llcrypt registered [ 7619.581745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7619.587321] alg: No test for adler32 (adler32-zlib) [ 7620.446498] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7620.532944] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7622.119139] Key type lgssc registered [ 7622.530528] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7626.373578] Lustre: DEBUG MARKER: Iteration 17 [ 7626.479473] LustreError: 408397:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7626.479498] LustreError: 408398:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7626.484866] LustreError: 408397:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7627.561026] Lustre: Mounted lustre-client [ 7627.561976] Lustre: Skipped 1 previous similar message [ 7628.142754] LustreError: 408511:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c301b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7628.148124] LustreError: 408511:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7628.226068] Lustre: Unmounted lustre-client [ 7629.167131] Key type lgssc unregistered [ 7629.287556] LNet: 408752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7629.291542] LNetError: 408752:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7629.302725] LNet: Removed LNI 192.168.204.58@tcp [ 7629.552129] Key type .llcrypt unregistered [ 7629.553423] Key type ._llcrypt unregistered [ 7629.805958] Key type ._llcrypt registered [ 7629.807532] Key type .llcrypt registered [ 7630.024928] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7630.029702] alg: No test for adler32 (adler32-zlib) [ 7630.886758] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7630.980772] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7632.567111] Key type lgssc registered [ 7632.969571] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7636.777418] Lustre: DEBUG MARKER: Iteration 18 [ 7636.879907] LustreError: 409581:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7636.880240] LustreError: 409582:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7636.885289] LustreError: 409581:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7637.943563] Lustre: Mounted lustre-client [ 7637.944577] Lustre: Skipped 1 previous similar message [ 7638.440212] LustreError: 409697:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c61db800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7638.442287] LustreError: 409696:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7638.445056] LustreError: 409697:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7638.499074] Lustre: Unmounted lustre-client [ 7639.500752] Key type lgssc unregistered [ 7639.618681] LNet: 409939:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7639.621366] LNetError: 409939:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7639.631684] LNet: Removed LNI 192.168.204.58@tcp [ 7639.873096] Key type .llcrypt unregistered [ 7639.874243] Key type ._llcrypt unregistered [ 7640.194578] Key type ._llcrypt registered [ 7640.201491] Key type .llcrypt registered [ 7640.371176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7640.377271] alg: No test for adler32 (adler32-zlib) [ 7641.241283] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7641.330364] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7642.911196] Key type lgssc registered [ 7643.282153] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7646.860035] Lustre: DEBUG MARKER: Iteration 19 [ 7646.961710] LustreError: 410769:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7646.963953] LustreError: 410770:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7646.966252] LustreError: 410769:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7648.022489] Lustre: Mounted lustre-client [ 7648.474228] LustreError: 410879:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d86f0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7648.476542] LustreError: 410880:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7648.478592] LustreError: 410879:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7648.518080] Lustre: Unmounted lustre-client [ 7649.451263] Key type lgssc unregistered [ 7649.558507] LNet: 411122:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7649.560935] LNetError: 411122:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7649.571612] LNet: Removed LNI 192.168.204.58@tcp [ 7649.799084] Key type .llcrypt unregistered [ 7649.800325] Key type ._llcrypt unregistered [ 7650.098454] Key type ._llcrypt registered [ 7650.099803] Key type .llcrypt registered [ 7650.261084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7650.266914] alg: No test for adler32 (adler32-zlib) [ 7651.122326] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7651.208582] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7652.807157] Key type lgssc registered [ 7653.195928] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7656.699847] Lustre: DEBUG MARKER: Iteration 20 [ 7656.824238] LustreError: 411949:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7656.824368] LustreError: 411951:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7656.829063] LustreError: 411949:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7657.891476] Lustre: Mounted lustre-client [ 7658.489347] LustreError: 412061:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c338f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7658.492052] LustreError: 412062:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7658.494946] LustreError: 412061:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7658.550731] Lustre: Unmounted lustre-client [ 7659.595288] Key type lgssc unregistered [ 7659.722530] LNet: 412304:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7659.725135] LNetError: 412304:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7659.732651] LNet: Removed LNI 192.168.204.58@tcp [ 7659.983155] Key type .llcrypt unregistered [ 7659.984945] Key type ._llcrypt unregistered [ 7660.262741] Key type ._llcrypt registered [ 7660.265647] Key type .llcrypt registered [ 7660.460300] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7660.465323] alg: No test for adler32 (adler32-zlib) [ 7661.335240] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7661.421771] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7663.007195] Key type lgssc registered [ 7663.460430] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7667.034665] Lustre: DEBUG MARKER: Iteration 21 [ 7667.147417] LustreError: 413134:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7667.148931] LustreError: 413135:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7667.153472] LustreError: 413134:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7668.213837] Lustre: Mounted lustre-client [ 7668.215115] Lustre: Skipped 1 previous similar message [ 7668.772770] LustreError: 413249:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238ef235800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7668.777327] LustreError: 413250:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7668.777787] LustreError: 413249:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7668.828125] Lustre: Unmounted lustre-client [ 7669.857278] Key type lgssc unregistered [ 7669.977486] LNet: 413492:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7669.980097] LNetError: 413492:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7669.987766] LNet: Removed LNI 192.168.204.58@tcp [ 7670.217118] Key type .llcrypt unregistered [ 7670.218272] Key type ._llcrypt unregistered [ 7670.501587] Key type ._llcrypt registered [ 7670.502591] Key type .llcrypt registered [ 7670.679369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7670.684200] alg: No test for adler32 (adler32-zlib) [ 7671.537089] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7671.616916] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7673.199118] Key type lgssc registered [ 7673.564136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7677.100291] Lustre: DEBUG MARKER: Iteration 22 [ 7677.204050] LustreError: 414321:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7677.205814] LustreError: 414322:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7677.208648] LustreError: 414321:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7678.275318] Lustre: Mounted lustre-client [ 7678.277040] Lustre: Skipped 1 previous similar message [ 7678.733133] LustreError: 414429:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d86f4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7678.737216] LustreError: 414430:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7678.739042] LustreError: 414429:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7678.777431] Lustre: Unmounted lustre-client [ 7679.707438] Key type lgssc unregistered [ 7679.811519] LNet: 414671:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7679.813990] LNetError: 414671:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7679.821564] LNet: Removed LNI 192.168.204.58@tcp [ 7680.058132] Key type .llcrypt unregistered [ 7680.059376] Key type ._llcrypt unregistered [ 7680.329540] Key type ._llcrypt registered [ 7680.330579] Key type .llcrypt registered [ 7680.520144] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7680.524984] alg: No test for adler32 (adler32-zlib) [ 7681.384233] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7681.465225] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7683.047196] Key type lgssc registered [ 7683.475587] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7687.236401] Lustre: DEBUG MARKER: Iteration 23 [ 7687.360532] LustreError: 415498:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7687.361102] LustreError: 415501:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7687.365306] LustreError: 415498:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7688.425858] Lustre: Mounted lustre-client [ 7688.427544] Lustre: Skipped 1 previous similar message [ 7688.910591] LustreError: 415608:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d86f4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7688.913479] LustreError: 415609:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7688.915357] LustreError: 415608:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7688.959698] Lustre: Unmounted lustre-client [ 7689.961542] Key type lgssc unregistered [ 7690.087731] LNet: 415850:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7690.090984] LNetError: 415850:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7690.099838] LNet: Removed LNI 192.168.204.58@tcp [ 7690.335104] Key type .llcrypt unregistered [ 7690.336439] Key type ._llcrypt unregistered [ 7690.606306] Key type ._llcrypt registered [ 7690.607556] Key type .llcrypt registered [ 7690.839026] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7690.845185] alg: No test for adler32 (adler32-zlib) [ 7691.710691] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7691.792325] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7693.383177] Key type lgssc registered [ 7693.784335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7697.542360] Lustre: DEBUG MARKER: Iteration 24 [ 7697.647548] LustreError: 416679:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7697.648612] LustreError: 416681:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7697.653269] LustreError: 416679:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7698.716261] Lustre: Mounted lustre-client [ 7699.213131] LustreError: 416792:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d19c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7699.221334] LustreError: 416792:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7699.223420] LustreError: 416792:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7699.277115] Lustre: Unmounted lustre-client [ 7700.276484] Key type lgssc unregistered [ 7700.396529] LNet: 417035:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7700.399232] LNetError: 417035:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7700.407713] LNet: Removed LNI 192.168.204.58@tcp [ 7700.655094] Key type .llcrypt unregistered [ 7700.656083] Key type ._llcrypt unregistered [ 7700.958105] Key type ._llcrypt registered [ 7700.959199] Key type .llcrypt registered [ 7701.127841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7701.133263] alg: No test for adler32 (adler32-zlib) [ 7701.995231] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7702.080289] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7703.671168] Key type lgssc registered [ 7704.075982] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7707.771506] Lustre: DEBUG MARKER: Iteration 25 [ 7707.883300] LustreError: 417863:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7707.883552] LustreError: 417864:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7707.889784] LustreError: 417863:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7708.951263] Lustre: Mounted lustre-client [ 7708.952872] Lustre: Skipped 1 previous similar message [ 7709.418031] LustreError: 417977:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cb3b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7709.422397] LustreError: 417977:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7709.424037] LustreError: 417978:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7709.472656] Lustre: Unmounted lustre-client [ 7710.452557] Key type lgssc unregistered [ 7710.578529] LNet: 418222:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7710.581279] LNetError: 418222:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7710.590610] LNet: Removed LNI 192.168.204.58@tcp [ 7710.843133] Key type .llcrypt unregistered [ 7710.844784] Key type ._llcrypt unregistered [ 7711.151869] Key type ._llcrypt registered [ 7711.153020] Key type .llcrypt registered [ 7711.351759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7711.357208] alg: No test for adler32 (adler32-zlib) [ 7712.219911] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7712.309755] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7713.895136] Key type lgssc registered [ 7714.302440] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7717.985274] Lustre: DEBUG MARKER: Iteration 26 [ 7718.100814] LustreError: 419050:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7718.100842] LustreError: 419051:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7718.107542] LustreError: 419050:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7719.168201] Lustre: Mounted lustre-client [ 7719.169897] Lustre: Skipped 1 previous similar message [ 7719.655834] LustreError: 419164:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c301d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7719.659179] LustreError: 419165:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7719.660065] LustreError: 419164:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7719.685358] Lustre: Unmounted lustre-client [ 7720.694790] Key type lgssc unregistered [ 7720.810473] LNet: 419408:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7720.812943] LNetError: 419408:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7720.822676] LNet: Removed LNI 192.168.204.58@tcp [ 7721.055107] Key type .llcrypt unregistered [ 7721.056243] Key type ._llcrypt unregistered [ 7721.340524] Key type ._llcrypt registered [ 7721.341632] Key type .llcrypt registered [ 7721.532671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7721.538701] alg: No test for adler32 (adler32-zlib) [ 7722.390334] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7722.477091] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7724.063174] Key type lgssc registered [ 7724.486930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7728.183621] Lustre: DEBUG MARKER: Iteration 27 [ 7728.278441] LustreError: 420236:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7728.279078] LustreError: 420238:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7728.283750] LustreError: 420236:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7729.339869] Lustre: Mounted lustre-client [ 7729.815324] LustreError: 420349:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0fa3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7729.818670] LustreError: 420348:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7729.821276] LustreError: 420349:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7729.876113] Lustre: Unmounted lustre-client [ 7730.841546] Key type lgssc unregistered [ 7730.967371] LNet: 420590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7730.970266] LNetError: 420590:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7730.978748] LNet: Removed LNI 192.168.204.58@tcp [ 7731.215133] Key type .llcrypt unregistered [ 7731.216963] Key type ._llcrypt unregistered [ 7731.487594] Key type ._llcrypt registered [ 7731.490860] Key type .llcrypt registered [ 7731.689480] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7731.695568] alg: No test for adler32 (adler32-zlib) [ 7732.552365] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7732.637482] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7734.223126] Key type lgssc registered [ 7734.620890] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7738.291166] Lustre: DEBUG MARKER: Iteration 28 [ 7738.412804] LustreError: 421418:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7738.415516] LustreError: 421419:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7738.420483] LustreError: 421418:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7739.497382] Lustre: Mounted lustre-client [ 7739.975323] LustreError: 421533:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c4108000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7739.977778] LustreError: 421534:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7739.979482] LustreError: 421533:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7740.012096] Lustre: Unmounted lustre-client [ 7741.037559] Key type lgssc unregistered [ 7741.157395] LNet: 421774:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7741.160279] LNetError: 421774:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7741.168381] LNet: Removed LNI 192.168.204.58@tcp [ 7741.417108] Key type .llcrypt unregistered [ 7741.418250] Key type ._llcrypt unregistered [ 7741.659949] Key type ._llcrypt registered [ 7741.661659] Key type .llcrypt registered [ 7741.902450] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7741.907924] alg: No test for adler32 (adler32-zlib) [ 7742.788350] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7742.880245] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7744.471170] Key type lgssc registered [ 7744.885852] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7748.762272] Lustre: DEBUG MARKER: Iteration 29 [ 7748.873823] LustreError: 422603:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7748.873860] LustreError: 422604:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7748.881042] LustreError: 422603:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7749.933894] Lustre: Mounted lustre-client [ 7750.444192] LustreError: 422718:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cb2b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7750.448350] LustreError: 422719:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7750.448413] LustreError: 422718:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7750.497475] Lustre: Unmounted lustre-client [ 7751.446288] Key type lgssc unregistered [ 7751.562527] LNet: 422961:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7751.564850] LNetError: 422961:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7751.572736] LNet: Removed LNI 192.168.204.58@tcp [ 7751.816122] Key type .llcrypt unregistered [ 7751.817647] Key type ._llcrypt unregistered [ 7752.084509] Key type ._llcrypt registered [ 7752.085909] Key type .llcrypt registered [ 7752.287863] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7752.294292] alg: No test for adler32 (adler32-zlib) [ 7753.162290] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7753.256915] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7754.839155] Key type lgssc registered [ 7755.251658] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7759.141165] Lustre: DEBUG MARKER: Iteration 30 [ 7759.242599] LustreError: 423789:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7759.242642] LustreError: 423790:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7759.246939] LustreError: 423789:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7760.310968] Lustre: Mounted lustre-client [ 7760.728246] LustreError: 423904:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238f42a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7760.732140] LustreError: 423904:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7760.733589] LustreError: 423905:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7760.737086] LustreError: 423905:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7760.788071] Lustre: Unmounted lustre-client [ 7760.789441] Lustre: Skipped 1 previous similar message [ 7761.701104] Key type lgssc unregistered [ 7761.812485] LNet: 424147:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7761.814937] LNetError: 424147:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7761.822628] LNet: Removed LNI 192.168.204.58@tcp [ 7762.083104] Key type .llcrypt unregistered [ 7762.084381] Key type ._llcrypt unregistered [ 7762.364079] Key type ._llcrypt registered [ 7762.365411] Key type .llcrypt registered [ 7762.554057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7762.558507] alg: No test for adler32 (adler32-zlib) [ 7763.417749] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7763.505680] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7765.095159] Key type lgssc registered [ 7765.461100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7768.924923] Lustre: DEBUG MARKER: Iteration 31 [ 7769.024757] LustreError: 424977:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7769.024800] LustreError: 424978:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7769.029128] LustreError: 424977:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7770.081072] Lustre: Mounted lustre-client [ 7770.083115] Lustre: Skipped 1 previous similar message [ 7770.534880] LustreError: 425091:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238ccd20800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7770.538116] LustreError: 425090:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7770.539644] LustreError: 425091:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7770.571248] Lustre: Unmounted lustre-client [ 7771.744169] Key type lgssc unregistered [ 7771.857751] LNet: 425332:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7771.862728] LNetError: 425332:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7771.873402] LNet: Removed LNI 192.168.204.58@tcp [ 7772.224170] Key type .llcrypt unregistered [ 7772.225503] Key type ._llcrypt unregistered [ 7772.547199] Key type ._llcrypt registered [ 7772.548738] Key type .llcrypt registered [ 7772.683104] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7772.691163] alg: No test for adler32 (adler32-zlib) [ 7773.542892] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7773.632317] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7775.215131] Key type lgssc registered [ 7775.593393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7779.269357] Lustre: DEBUG MARKER: Iteration 32 [ 7779.364705] LustreError: 426161:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7779.364957] LustreError: 426162:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7779.369945] LustreError: 426161:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7780.427127] Lustre: Mounted lustre-client [ 7780.428490] Lustre: Skipped 1 previous similar message [ 7780.869364] LustreError: 426274:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d86f1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7780.871326] LustreError: 426275:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7780.872713] LustreError: 426274:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7780.938236] Lustre: Unmounted lustre-client [ 7781.864603] Key type lgssc unregistered [ 7781.973479] LNet: 426516:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7781.976194] LNetError: 426516:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7781.987744] LNet: Removed LNI 192.168.204.58@tcp [ 7782.220099] Key type .llcrypt unregistered [ 7782.221196] Key type ._llcrypt unregistered [ 7782.494114] Key type ._llcrypt registered [ 7782.495281] Key type .llcrypt registered [ 7782.676925] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7782.681464] alg: No test for adler32 (adler32-zlib) [ 7783.536402] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7783.617608] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7785.207090] Key type lgssc registered [ 7785.591520] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7789.300435] Lustre: DEBUG MARKER: Iteration 33 [ 7789.395235] LustreError: 427357:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7789.395265] LustreError: 427358:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7789.399464] LustreError: 427357:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7790.451913] Lustre: Mounted lustre-client [ 7790.907165] LustreError: 427470:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c40f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7790.909604] LustreError: 427471:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7790.911468] LustreError: 427470:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7790.951053] Lustre: Unmounted lustre-client [ 7791.982862] Key type lgssc unregistered [ 7792.107536] LNet: 427712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7792.109946] LNetError: 427712:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7792.120708] LNet: Removed LNI 192.168.204.58@tcp [ 7792.347136] Key type .llcrypt unregistered [ 7792.348435] Key type ._llcrypt unregistered [ 7792.642201] Key type ._llcrypt registered [ 7792.643122] Key type .llcrypt registered [ 7792.827931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7792.833394] alg: No test for adler32 (adler32-zlib) [ 7793.686574] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7793.772195] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7795.351172] Key type lgssc registered [ 7795.762151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7799.411232] Lustre: DEBUG MARKER: Iteration 34 [ 7799.513152] LustreError: 428540:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7799.514151] LustreError: 428541:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7799.517296] LustreError: 428540:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7800.572495] Lustre: Mounted lustre-client [ 7800.573510] Lustre: Skipped 1 previous similar message [ 7801.046447] LustreError: 428657:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0fa7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7801.049037] LustreError: 428656:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7801.050491] LustreError: 428657:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7801.090237] Lustre: Unmounted lustre-client [ 7802.033871] Key type lgssc unregistered [ 7802.166809] LNet: 428898:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7802.170492] LNetError: 428898:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7802.181603] LNet: Removed LNI 192.168.204.58@tcp [ 7802.435141] Key type .llcrypt unregistered [ 7802.436386] Key type ._llcrypt unregistered [ 7802.700951] Key type ._llcrypt registered [ 7802.702149] Key type .llcrypt registered [ 7802.910386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7802.915862] alg: No test for adler32 (adler32-zlib) [ 7803.774759] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7803.859030] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7805.447147] Key type lgssc registered [ 7805.838357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7809.635202] Lustre: DEBUG MARKER: Iteration 35 [ 7809.751652] LustreError: 429727:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7809.752785] LustreError: 429728:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7809.758246] LustreError: 429727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7810.819993] Lustre: Mounted lustre-client [ 7810.821114] Lustre: Skipped 1 previous similar message [ 7811.274204] LustreError: 429843:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0e9c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7811.277415] LustreError: 429842:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7811.278258] LustreError: 429843:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7811.330077] Lustre: Unmounted lustre-client [ 7812.331722] Key type lgssc unregistered [ 7812.453495] LNet: 430085:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7812.456248] LNetError: 430085:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7812.463743] LNet: Removed LNI 192.168.204.58@tcp [ 7812.700122] Key type .llcrypt unregistered [ 7812.701374] Key type ._llcrypt unregistered [ 7812.977608] Key type ._llcrypt registered [ 7812.978689] Key type .llcrypt registered [ 7813.181777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7813.187694] alg: No test for adler32 (adler32-zlib) [ 7814.052716] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7814.148158] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7815.735139] Key type lgssc registered [ 7816.151509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7819.872804] Lustre: DEBUG MARKER: Iteration 36 [ 7819.974393] LustreError: 430913:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7819.974661] LustreError: 430914:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7819.979697] LustreError: 430913:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7821.040699] Lustre: Mounted lustre-client [ 7821.529555] LustreError: 431025:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d02a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7821.535175] LustreError: 431025:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7821.538453] LustreError: 431025:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7821.580067] Lustre: Unmounted lustre-client [ 7822.538116] Key type lgssc unregistered [ 7822.652494] LNet: 431268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7822.655917] LNetError: 431268:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7822.663504] LNet: Removed LNI 192.168.204.58@tcp [ 7822.912097] Key type .llcrypt unregistered [ 7822.913686] Key type ._llcrypt unregistered [ 7823.225264] Key type ._llcrypt registered [ 7823.226595] Key type .llcrypt registered [ 7823.377393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7823.382650] alg: No test for adler32 (adler32-zlib) [ 7824.240829] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7824.325955] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7825.911099] Key type lgssc registered [ 7826.301886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7829.762778] Lustre: DEBUG MARKER: Iteration 37 [ 7829.857536] LustreError: 432096:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7829.861437] LustreError: 432097:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7829.863549] LustreError: 432096:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7830.921480] Lustre: Mounted lustre-client [ 7830.923263] Lustre: Skipped 1 previous similar message [ 7831.343905] LustreError: 432212:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238e0527800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7831.349256] LustreError: 432212:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7831.453406] Lustre: Unmounted lustre-client [ 7831.454610] Lustre: Skipped 1 previous similar message [ 7832.380089] Key type lgssc unregistered [ 7832.496431] LNet: 432456:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7832.499568] LNetError: 432456:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7832.508811] LNet: Removed LNI 192.168.204.58@tcp [ 7832.739103] Key type .llcrypt unregistered [ 7832.740925] Key type ._llcrypt unregistered [ 7833.029265] Key type ._llcrypt registered [ 7833.030259] Key type .llcrypt registered [ 7833.207829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7833.212897] alg: No test for adler32 (adler32-zlib) [ 7834.065481] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7834.146123] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7835.727142] Key type lgssc registered [ 7836.121197] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7840.014472] Lustre: DEBUG MARKER: Iteration 38 [ 7840.118374] LustreError: 433285:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7840.119810] LustreError: 433284:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7840.125072] LustreError: 433285:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7841.182372] Lustre: Mounted lustre-client [ 7841.654538] LustreError: 433395:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0e9c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7841.662229] LustreError: 433395:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7841.665492] LustreError: 433395:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7841.707105] Lustre: Unmounted lustre-client [ 7842.626881] Key type lgssc unregistered [ 7842.739376] LNet: 433637:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7842.741606] LNetError: 433637:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7842.748582] LNet: Removed LNI 192.168.204.58@tcp [ 7842.999111] Key type .llcrypt unregistered [ 7843.000343] Key type ._llcrypt unregistered [ 7843.289698] Key type ._llcrypt registered [ 7843.291991] Key type .llcrypt registered [ 7843.471917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7843.477079] alg: No test for adler32 (adler32-zlib) [ 7844.356261] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7844.448637] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7846.031131] Key type lgssc registered [ 7846.404117] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7849.920498] Lustre: DEBUG MARKER: Iteration 39 [ 7850.025371] LustreError: 434466:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7850.025498] LustreError: 434467:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7850.030423] LustreError: 434466:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7851.079052] Lustre: Mounted lustre-client [ 7851.080830] Lustre: Skipped 1 previous similar message [ 7851.662540] LustreError: 434579:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d19c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7851.664653] LustreError: 434578:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7851.666344] LustreError: 434579:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7851.705127] Lustre: Unmounted lustre-client [ 7852.735850] Key type lgssc unregistered [ 7852.847437] LNet: 434821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7852.849966] LNetError: 434821:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7852.857858] LNet: Removed LNI 192.168.204.58@tcp [ 7853.096148] Key type .llcrypt unregistered [ 7853.097816] Key type ._llcrypt unregistered [ 7853.408976] Key type ._llcrypt registered [ 7853.410041] Key type .llcrypt registered [ 7853.583397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7853.589432] alg: No test for adler32 (adler32-zlib) [ 7854.450088] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7854.535523] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7856.127178] Key type lgssc registered [ 7856.592649] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7860.258838] Lustre: DEBUG MARKER: Iteration 40 [ 7860.367479] LustreError: 435649:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7860.367537] LustreError: 435650:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7860.374095] LustreError: 435649:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7861.433863] Lustre: Mounted lustre-client [ 7861.883196] LustreError: 435760:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d19c3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7861.887292] LustreError: 435760:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7861.887651] LustreError: 435761:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7861.924493] Lustre: Unmounted lustre-client [ 7862.844424] Key type lgssc unregistered [ 7862.959509] LNet: 436003:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7862.961838] LNetError: 436003:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7862.972676] LNet: Removed LNI 192.168.204.58@tcp [ 7863.229140] Key type .llcrypt unregistered [ 7863.230667] Key type ._llcrypt unregistered [ 7863.525565] Key type ._llcrypt registered [ 7863.527445] Key type .llcrypt registered [ 7863.724666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7863.732027] alg: No test for adler32 (adler32-zlib) [ 7864.593429] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7864.678050] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7866.263085] Key type lgssc registered [ 7866.689643] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7870.423313] Lustre: DEBUG MARKER: Iteration 41 [ 7870.549910] LustreError: 436831:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7870.551315] LustreError: 436832:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7870.554305] LustreError: 436831:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7871.615901] Lustre: Mounted lustre-client [ 7872.107649] LustreError: 436944:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c622a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7872.111508] LustreError: 436944:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7872.111542] LustreError: 436945:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7872.115884] LustreError: 436945:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7872.155127] Lustre: Unmounted lustre-client [ 7873.153696] Key type lgssc unregistered [ 7873.265533] LNet: 437185:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7873.267827] LNetError: 437185:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7873.278823] LNet: Removed LNI 192.168.204.58@tcp [ 7873.507107] Key type .llcrypt unregistered [ 7873.508473] Key type ._llcrypt unregistered [ 7873.775757] Key type ._llcrypt registered [ 7873.776754] Key type .llcrypt registered [ 7873.972055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7873.977118] alg: No test for adler32 (adler32-zlib) [ 7874.839203] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7874.921837] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7876.503185] Key type lgssc registered [ 7876.883884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7880.360120] Lustre: DEBUG MARKER: Iteration 42 [ 7880.459299] LustreError: 438014:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7880.459336] LustreError: 438015:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7880.463532] LustreError: 438014:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7881.513968] Lustre: Mounted lustre-client [ 7881.980251] LustreError: 438126:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0135000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7881.986924] LustreError: 438126:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7881.988901] LustreError: 438126:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7882.024544] Lustre: Unmounted lustre-client [ 7882.025792] Lustre: Skipped 1 previous similar message [ 7883.006706] Key type lgssc unregistered [ 7883.127541] LNet: 438368:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7883.129762] LNetError: 438368:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7883.138682] LNet: Removed LNI 192.168.204.58@tcp [ 7883.371122] Key type .llcrypt unregistered [ 7883.373039] Key type ._llcrypt unregistered [ 7883.677112] Key type ._llcrypt registered [ 7883.678980] Key type .llcrypt registered [ 7883.879465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7883.885877] alg: No test for adler32 (adler32-zlib) [ 7884.750654] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7884.840574] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7886.423185] Key type lgssc registered [ 7886.824233] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7890.391654] Lustre: DEBUG MARKER: Iteration 43 [ 7890.510675] LustreError: 439198:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7890.510927] LustreError: 439197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7890.515940] LustreError: 439198:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7891.571209] Lustre: Mounted lustre-client [ 7891.572284] Lustre: Skipped 1 previous similar message [ 7892.023500] LustreError: 439307:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d1d58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7892.026216] LustreError: 439308:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7892.027180] LustreError: 439307:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7892.072321] Lustre: Unmounted lustre-client [ 7893.029887] Key type lgssc unregistered [ 7893.150421] LNet: 439550:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7893.152992] LNetError: 439550:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7893.159809] LNet: Removed LNI 192.168.204.58@tcp [ 7893.412112] Key type .llcrypt unregistered [ 7893.413218] Key type ._llcrypt unregistered [ 7893.783246] Key type ._llcrypt registered [ 7893.784228] Key type .llcrypt registered [ 7893.929540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7893.935920] alg: No test for adler32 (adler32-zlib) [ 7894.816236] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7894.907312] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7896.495144] Key type lgssc registered [ 7896.942098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7900.575805] Lustre: DEBUG MARKER: Iteration 44 [ 7900.698773] LustreError: 440379:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7900.698873] LustreError: 440378:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7900.704113] LustreError: 440379:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7901.768649] Lustre: Mounted lustre-client [ 7902.331371] LustreError: 440493:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d0fa2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7902.333911] LustreError: 440492:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7902.335805] LustreError: 440493:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7902.371120] Lustre: Unmounted lustre-client [ 7903.361848] Key type lgssc unregistered [ 7903.476563] LNet: 440733:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7903.479211] LNetError: 440733:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7903.485761] LNet: Removed LNI 192.168.204.58@tcp [ 7903.709115] Key type .llcrypt unregistered [ 7903.710901] Key type ._llcrypt unregistered [ 7903.980899] Key type ._llcrypt registered [ 7903.981938] Key type .llcrypt registered [ 7904.165471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7904.170959] alg: No test for adler32 (adler32-zlib) [ 7905.033678] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7905.120654] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7906.703122] Key type lgssc registered [ 7907.095165] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7910.754593] Lustre: DEBUG MARKER: Iteration 45 [ 7910.887137] LustreError: 441562:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7910.887156] LustreError: 441561:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7910.893609] LustreError: 441562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7911.967664] Lustre: Mounted lustre-client [ 7911.968694] Lustre: Skipped 1 previous similar message [ 7912.527839] LustreError: 441672:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c410b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7912.534868] LustreError: 441672:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7912.537133] LustreError: 441672:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7912.587688] Lustre: Unmounted lustre-client [ 7912.588853] Lustre: Skipped 1 previous similar message [ 7913.610550] Key type lgssc unregistered [ 7913.731496] LNet: 441916:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7913.734626] LNetError: 441916:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7913.742708] LNet: Removed LNI 192.168.204.58@tcp [ 7913.987127] Key type .llcrypt unregistered [ 7913.988490] Key type ._llcrypt unregistered [ 7914.266613] Key type ._llcrypt registered [ 7914.270775] Key type .llcrypt registered [ 7914.468287] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7914.473445] alg: No test for adler32 (adler32-zlib) [ 7915.333544] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7915.417595] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7916.999179] Key type lgssc registered [ 7917.384107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7920.959769] Lustre: DEBUG MARKER: Iteration 46 [ 7921.058297] LustreError: 442744:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7921.058324] LustreError: 442745:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7921.063408] LustreError: 442744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7922.130456] Lustre: Mounted lustre-client [ 7922.131508] Lustre: Skipped 1 previous similar message [ 7922.585305] LustreError: 442858:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d15a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7922.591686] LustreError: 442859:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7922.593819] LustreError: 442859:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7922.647080] Lustre: Unmounted lustre-client [ 7923.594526] Key type lgssc unregistered [ 7923.700530] LNet: 443100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7923.702811] LNetError: 443100:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7923.713881] LNet: Removed LNI 192.168.204.58@tcp [ 7923.961112] Key type .llcrypt unregistered [ 7923.962170] Key type ._llcrypt unregistered [ 7924.240639] Key type ._llcrypt registered [ 7924.247799] Key type .llcrypt registered [ 7924.430443] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7924.435891] alg: No test for adler32 (adler32-zlib) [ 7925.288051] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7925.374251] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7926.959184] Key type lgssc registered [ 7927.362578] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7931.142345] Lustre: DEBUG MARKER: Iteration 47 [ 7931.242868] LustreError: 443929:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7931.243089] LustreError: 443930:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7931.247090] LustreError: 443929:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7932.300123] Lustre: Mounted lustre-client [ 7932.742827] LustreError: 444043:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d1d5d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7932.747509] LustreError: 444043:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 7932.749703] LustreError: 444043:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7932.784484] Lustre: Unmounted lustre-client [ 7933.734332] Key type lgssc unregistered [ 7933.854574] LNet: 444285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7933.857877] LNetError: 444285:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7933.867932] LNet: Removed LNI 192.168.204.58@tcp [ 7934.136088] Key type .llcrypt unregistered [ 7934.137573] Key type ._llcrypt unregistered [ 7934.387846] Key type ._llcrypt registered [ 7934.388991] Key type .llcrypt registered [ 7934.592351] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7934.599661] alg: No test for adler32 (adler32-zlib) [ 7935.453510] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7935.530485] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7937.119149] Key type lgssc registered [ 7937.691884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7941.267490] Lustre: DEBUG MARKER: Iteration 48 [ 7941.392635] LustreError: 445113:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7941.393430] LustreError: 445114:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7941.397899] LustreError: 445113:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7942.465963] Lustre: Mounted lustre-client [ 7942.467123] Lustre: Skipped 1 previous similar message [ 7943.049986] LustreError: 445226:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cd53b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7943.055168] LustreError: 445227:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7943.056169] LustreError: 445226:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7943.106204] Lustre: Unmounted lustre-client [ 7944.022874] Key type lgssc unregistered [ 7944.139502] LNet: 445470:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7944.142244] LNetError: 445470:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7944.150748] LNet: Removed LNI 192.168.204.58@tcp [ 7944.395101] Key type .llcrypt unregistered [ 7944.396803] Key type ._llcrypt unregistered [ 7944.689594] Key type ._llcrypt registered [ 7944.690687] Key type .llcrypt registered [ 7944.867248] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7944.871621] alg: No test for adler32 (adler32-zlib) [ 7945.728177] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7945.805024] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7947.383202] Key type lgssc registered [ 7947.781337] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7951.390156] Lustre: DEBUG MARKER: Iteration 49 [ 7951.487885] LustreError: 446298:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7951.487935] LustreError: 446299:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7951.493804] LustreError: 446298:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7952.549464] Lustre: Mounted lustre-client [ 7952.550563] Lustre: Skipped 1 previous similar message [ 7952.997712] LustreError: 446413:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238cbe53800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7953.005305] LustreError: 446413:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7953.008884] LustreError: 446413:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7953.027119] Lustre: Unmounted lustre-client [ 7954.042869] Key type lgssc unregistered [ 7954.157522] LNet: 446655:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7954.159955] LNetError: 446655:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7954.169721] LNet: Removed LNI 192.168.204.58@tcp [ 7954.391130] Key type .llcrypt unregistered [ 7954.392165] Key type ._llcrypt unregistered [ 7954.606267] Key type ._llcrypt registered [ 7954.607581] Key type .llcrypt registered [ 7954.764701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7954.774566] alg: No test for adler32 (adler32-zlib) [ 7955.632506] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7955.715130] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7957.295139] Key type lgssc registered [ 7957.699642] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7961.384670] Lustre: DEBUG MARKER: Iteration 50 [ 7961.493943] LustreError: 447482:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7961.493974] LustreError: 447483:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7961.499605] LustreError: 447482:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7962.558205] Lustre: Mounted lustre-client [ 7963.144097] LustreError: 447588:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238d1bfb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7963.150768] LustreError: 447588:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7963.154060] LustreError: 447588:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7963.173185] Lustre: Unmounted lustre-client [ 7964.250517] Key type lgssc unregistered [ 7964.380946] LNet: 447831:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7964.383438] LNetError: 447831:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7964.393490] LNet: Removed LNI 192.168.204.58@tcp [ 7964.686169] Key type .llcrypt unregistered [ 7964.687570] Key type ._llcrypt unregistered [ 7965.002141] Key type ._llcrypt registered [ 7965.003892] Key type .llcrypt registered [ 7965.296848] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7965.302205] alg: No test for adler32 (adler32-zlib) [ 7966.209358] Lustre: Lustre: Build Version: 2.17.50_3_gca5b8be [ 7966.310913] LNet: Added LNI 192.168.204.58@tcp [8/256/0/180] [ 7967.903206] Key type lgssc registered [ 7968.433541] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7972.821613] Lustre: Mounted lustre-client [ 7975.234191] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 07:57:45 (1769086665) [ 7991.775169] Lustre: 449185:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086666/real 1769086666] req@ffff9238ef971180 x1855021809411968/t0(0) o36->lustre-MDT0000-mdc-ffff9238c3b14000@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1769086682 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7991.782103] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7991.791047] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8008.159207] Lustre: 449185:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086682/real 1769086682] req@ffff9238ef971180 x1855021809411968/t0(0) o36->lustre-MDT0000-mdc-ffff9238c3b14000@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1769086698 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8008.170379] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8008.183722] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8024.543197] Lustre: 449185:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086698/real 1769086698] req@ffff9238ef971180 x1855021809411968/t0(0) o36->lustre-MDT0000-mdc-ffff9238c3b14000@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1769086714 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8024.560590] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8024.576715] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8039.903115] Lustre: 449185:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086714/real 1769086714] req@ffff9238ef971180 x1855021809411968/t0(0) o36->lustre-MDT0000-mdc-ffff9238c3b14000@192.168.204.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1769086730 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8039.913308] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection to lustre-MDT0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8039.921295] Lustre: lustre-MDT0000-mdc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8040.496152] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 07:58:50 (1769086730) [ 8046.196753] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 07:58:56 (1769086736) [ 8049.455203] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 07:58:59 (1769086739) [ 8051.714306] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 07:59:01 (1769086741) [ 8058.947646] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 07:59:08 (1769086748) [ 8071.062619] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 07:59:20 (1769086760) [ 8073.410513] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 07:59:23 (1769086763) [ 8073.481585] LustreError: 453958:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 8075.567140] LustreError: 453958:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 8077.890824] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 07:59:27 (1769086767) [ 8096.223267] Lustre: 448021:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086770/real 1769086770] req@ffff9238d3530000 x1855021810446208/t0(0) o4->lustre-OST0000-osc-ffff9238c3b14000@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769086786 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8096.223332] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8096.242190] Lustre: 448021:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 8112.607225] Lustre: 448020:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086786/real 1769086786] req@ffff9238e019a300 x1855021810448512/t0(0) o4->lustre-OST0000-osc-ffff9238c3b14000@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769086802 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8112.607272] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8112.617284] Lustre: 448020:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 8112.635724] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8128.799276] Lustre: 448022:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1769086803/real 1769086803] req@ffff9238ecec7b80 x1855021810448896/t0(0) o4->lustre-OST0000-osc-ffff9238c3b14000@192.168.204.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1769086819 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8128.820110] Lustre: 448022:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 8128.826574] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection to lustre-OST0000 (at 192.168.204.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8128.843599] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8145.251576] Lustre: lustre-OST0000-osc-ffff9238c3b14000: Connection restored to 192.168.204.158@tcp (at 192.168.204.158@tcp) [ 8168.649155] Lustre: DEBUG MARKER: oleg458-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9238c3b14000.ost_server_uuid 50 [ 8169.191636] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9238c3b14000.ost_server_uuid in FULL state after 0 sec [ 8169.790336] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8170.441992] Lustre: DEBUG MARKER: == sanityn test complete, duration 7915 sec ============== 08:01:00 (1769086860) [ 8170.959467] Lustre: DEBUG MARKER: === sanityn: start cleanup 08:01:00 (1769086860) === [ 8235.152311] LustreError: 456020:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c4718000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8235.156088] LustreError: 456020:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8235.175488] Lustre: Unmounted lustre-client [ 8236.598825] Lustre: DEBUG MARKER: === sanityn: finish cleanup 08:02:06 (1769086926) === [ 8237.120291] LustreError: 456325:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9238c3b14000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8237.124294] LustreError: 456325:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8237.129126] LustreError: 456325:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8237.131321] LustreError: 456325:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8237.157152] Lustre: Unmounted lustre-client [ 8275.609520] Key type lgssc unregistered [ 8275.731804] LNet: 457008:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8275.734705] LNetError: 457008:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8275.744737] LNet: Removed LNI 192.168.204.58@tcp [ 8276.004134] Key type .llcrypt unregistered [ 8276.005847] Key type ._llcrypt unregistered