[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 513630523 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.003090] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005010] kvm-guest: setup PV IPIs [ 0.007878] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009010] pid_max: default: 32768 minimum: 301 [ 0.010132] LSM: Security Framework initializing [ 0.011036] Yama: becoming mindful. [ 0.012025] SELinux: Initializing. [ 0.014012] *** VALIDATE selinux *** [ 0.022688] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025098] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026139] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027256] *** VALIDATE tmpfs *** [ 0.028693] *** VALIDATE proc *** [ 0.029293] *** VALIDATE cgroup *** [ 0.030009] *** VALIDATE cgroup2 *** [ 0.031281] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032153] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033022] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034033] Spectre V2 : User space: Vulnerable [ 0.035012] Speculative Store Bypass: Vulnerable [ 0.038845] debug: unmapping init [mem 0xffffffffbb059000-0xffffffffbb060fff] [ 0.040147] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041750] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042024] ... version: 2 [ 0.043012] ... bit width: 48 [ 0.044012] ... generic registers: 4 [ 0.045012] ... value mask: 0000ffffffffffff [ 0.046011] ... max period: 00007fffffffffff [ 0.047010] ... fixed-purpose events: 3 [ 0.048008] ... event mask: 000000070000000f [ 0.050253] rcu: Hierarchical SRCU implementation. [ 0.052603] smp: Bringing up secondary CPUs ... [ 0.053650] x86: Booting SMP configuration: [ 0.054023] .... node #0, CPUs: #1 #2 #3 [ 0.061176] smp: Brought up 1 node, 4 CPUs [ 0.063023] smpboot: Max logical packages: 1 [ 0.064019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.246344] node 0 deferred pages initialised in 179ms [ 0.249345] devtmpfs: initialized [ 0.251391] x86/mm: Memory block size: 128MB [ 0.255048] gcov: version magic: 0x41383552 [ 0.258387] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.261118] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.264440] pinctrl core: initialized pinctrl subsystem [ 0.266176] [ 0.266635] ************************************************************* [ 0.269017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.271015] ** ** [ 0.273019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.275018] ** ** [ 0.278021] ** This means that this kernel is built to expose internal ** [ 0.279018] ** IOMMU data structures, which may compromise security on ** [ 0.281018] ** your system. ** [ 0.283018] ** ** [ 0.285020] ** If you see this message and you are not debugging the ** [ 0.288020] ** kernel, report this immediately to your vendor! ** [ 0.290014] ** ** [ 0.292018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.295018] ************************************************************* [ 0.297815] NET: Registered protocol family 16 [ 0.299501] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.302093] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.304074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.308031] cpuidle: using governor menu [ 0.309717] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.313495] PCI: Using configuration type 1 for base access [ 0.315165] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.325189] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.326065] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.329056] cryptd: max_cpu_qlen set to 1000 [ 0.331347] ACPI: Added _OSI(Module Device) [ 0.332021] ACPI: Added _OSI(Processor Device) [ 0.334020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.335029] ACPI: Added _OSI(Processor Aggregator Device) [ 0.340000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.345525] ACPI: Interpreter enabled [ 0.346000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.349012] ACPI: Using IOAPIC for interrupt routing [ 0.351104] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.354450] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.362714] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.364040] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.367021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.370077] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.374281] acpiphp: Slot [2] registered [ 0.377196] acpiphp: Slot [3] registered [ 0.378095] acpiphp: Slot [4] registered [ 0.379088] acpiphp: Slot [5] registered [ 0.380127] acpiphp: Slot [6] registered [ 0.381000] acpiphp: Slot [7] registered [ 0.381000] acpiphp: Slot [8] registered [ 0.381054] acpiphp: Slot [9] registered [ 0.381926] acpiphp: Slot [10] registered [ 0.382000] acpiphp: Slot [11] registered [ 0.384091] acpiphp: Slot [12] registered [ 0.385099] acpiphp: Slot [13] registered [ 0.387107] acpiphp: Slot [14] registered [ 0.388091] acpiphp: Slot [15] registered [ 0.390136] acpiphp: Slot [16] registered [ 0.391079] acpiphp: Slot [17] registered [ 0.392069] acpiphp: Slot [18] registered [ 0.393000] acpiphp: Slot [19] registered [ 0.393000] acpiphp: Slot [20] registered [ 0.394131] acpiphp: Slot [21] registered [ 0.396112] acpiphp: Slot [22] registered [ 0.398103] acpiphp: Slot [23] registered [ 0.399110] acpiphp: Slot [24] registered [ 0.401082] acpiphp: Slot [25] registered [ 0.402114] acpiphp: Slot [26] registered [ 0.403149] acpiphp: Slot [27] registered [ 0.405125] acpiphp: Slot [28] registered [ 0.406079] acpiphp: Slot [29] registered [ 0.409152] acpiphp: Slot [30] registered [ 0.410137] acpiphp: Slot [31] registered [ 0.412069] PCI host bridge to bus 0000:00 [ 0.413018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.415025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.418022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.421036] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.424024] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.427035] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.429191] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.434052] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.436994] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.443213] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.446947] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.449013] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.450008] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.451008] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.452464] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.454663] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.456037] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.458560] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.462017] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.468617] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.472012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.477173] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.484017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.488014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.499021] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.512167] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.518023] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.523029] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.545021] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.559723] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.561348] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.563343] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.565402] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.567316] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.571109] iommu: Default domain type: Passthrough [ 0.573461] SCSI subsystem initialized [ 0.575194] ACPI: bus type USB registered [ 0.576098] usbcore: registered new interface driver usbfs [ 0.577080] usbcore: registered new interface driver hub [ 0.578083] usbcore: registered new device driver usb [ 0.580169] pps_core: LinuxPPS API ver. 1 registered [ 0.581008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.583053] PTP clock support registered [ 0.585149] EDAC MC: Ver: 3.0.0 [ 0.586452] PCI: Using ACPI for IRQ routing [ 0.588554] NetLabel: Initializing [ 0.590019] NetLabel: domain hash size = 128 [ 0.592014] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.593092] NetLabel: unlabeled traffic allowed by default [ 0.596021] vgaarb: loaded [ 0.598003] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.599014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.605000] clocksource: Switched to clocksource kvm-clock [ 0.731944] VFS: Disk quotas dquot_6.6.0 [ 0.733756] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.736214] *** VALIDATE ramfs *** [ 0.737568] *** VALIDATE hugetlbfs *** [ 0.739264] pnp: PnP ACPI init [ 0.741791] pnp: PnP ACPI: found 6 devices [ 0.762923] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.766371] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.768469] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.773036] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.775268] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.777443] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.781784] NET: Registered protocol family 2 [ 0.784093] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.790543] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.794277] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.798849] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.802224] TCP: Hash tables configured (established 65536 bind 65536) [ 0.804747] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.807717] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.810465] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.813709] NET: Registered protocol family 1 [ 0.817180] RPC: Registered named UNIX socket transport module. [ 0.819289] RPC: Registered udp transport module. [ 0.821020] RPC: Registered tcp transport module. [ 0.822819] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.825114] NET: Registered protocol family 44 [ 0.826539] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.828982] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.830857] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.832682] PCI: CLS 0 bytes, default 64 [ 0.833876] Unpacking initramfs... [ 2.300106] debug: unmapping init [mem 0xffff9f01bcc64000-0xffff9f01bffcffff] [ 2.303516] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.305035] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.307878] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.883891] Initialise system trusted keyrings [ 2.885297] Key type blacklist registered [ 2.887092] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.896551] zbud: loaded [ 2.900600] *** VALIDATE nfs *** [ 2.901613] *** VALIDATE nfs4 *** [ 2.903104] pstore: using deflate compression [ 2.906644] Platform Keyring initialized [ 3.056134] NET: Registered protocol family 38 [ 3.057532] Key type asymmetric registered [ 3.058976] Asymmetric key parser 'x509' registered [ 3.061155] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.064919] io scheduler mq-deadline registered [ 3.066769] io scheduler kyber registered [ 3.067980] io scheduler bfq registered [ 3.071628] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.080789] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.083298] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.086447] ACPI: Power Button [PWRF] [ 3.187632] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.285143] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.390056] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.421604] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.459499] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.466854] Non-volatile memory driver v1.3 [ 3.468206] Linux agpgart interface v0.103 [ 3.501084] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.503207] vda: detected capacity change from 0 to 68530176 [ 3.520225] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.522954] vdb: detected capacity change from 0 to 1073741824 [ 3.529351] libphy: Fixed MDIO Bus: probed [ 3.538556] usbcore: registered new interface driver usbserial_generic [ 3.541542] usbserial: USB Serial support registered for generic [ 3.543640] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.549270] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.550917] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.553287] mousedev: PS/2 mouse device common for all mice [ 3.555690] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.558686] rtc_cmos 00:05: RTC can wake from S4 [ 3.563967] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.568141] rtc_cmos 00:05: registered as rtc0 [ 3.571958] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.574692] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.579541] intel_pstate: CPU model not supported [ 3.587600] hid: raw HID events driver (C) Jiri Kosina [ 3.589598] usbcore: registered new interface driver usbhid [ 3.591342] usbhid: USB HID core driver [ 3.592825] drop_monitor: Initializing network drop monitor service [ 3.595992] Initializing XFRM netlink socket [ 3.598215] NET: Registered protocol family 10 [ 3.601053] Segment Routing with IPv6 [ 3.602179] NET: Registered protocol family 17 [ 3.604573] mpls_gso: MPLS GSO support [ 3.615673] RAS: Correctable Errors collector initialized. [ 3.618303] AVX version of gcm_enc/dec engaged. [ 3.620331] AES CTR mode by8 optimization enabled [ 3.759586] sched_clock: Marking stable (3759562219, 0)->(4637228402, -877666183) [ 3.763181] registered taskstats version 1 [ 3.766555] Loading compiled-in X.509 certificates [ 3.769637] zswap: loaded using pool lzo/zbud [ 3.815559] Key type big_key registered [ 3.836795] Key type encrypted registered [ 3.838793] ima: No TPM chip found, activating TPM-bypass! [ 3.844881] ima: Allocated hash algorithm: sha1 [ 3.846750] ima: No architecture policies found [ 3.854151] evm: Initialising EVM extended attributes: [ 3.856738] evm: security.selinux [ 3.857950] evm: security.ima [ 3.859451] evm: security.capability [ 3.860895] evm: HMAC attrs: 0x1 [ 3.866951] rtc_cmos 00:05: setting system clock to 2025-11-16 22:27:32 UTC (1763332052) [ 3.875799] debug: unmapping init [mem 0xffffffffbc003000-0xffffffffbc1fffff] [ 3.879446] debug: unmapping init [mem 0xffffffffbad82000-0xffffffffbb058fff] [ 3.892347] Write protecting the kernel read-only data: 28672k [ 3.896655] debug: unmapping init [mem 0xffffffffb9403000-0xffffffffb95fffff] [ 3.899983] debug: unmapping init [mem 0xffffffffb9d14000-0xffffffffb9dfffff] [ 3.943082] 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.953360] systemd[1]: Detected virtualization kvm. [ 3.954749] systemd[1]: Detected architecture x86-64. [ 3.956268] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.993247] systemd[1]: No hostname configured. [ 3.994776] systemd[1]: Set hostname to . [ 3.997306] random: systemd: uninitialized urandom read (16 bytes read) [ 4.000354] systemd[1]: Initializing machine ID from random generator. [ 4.184450] random: systemd: uninitialized urandom read (16 bytes read) [ 4.189414] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 4.197910] random: systemd: uninitialized urandom read (16 bytes read) [ 4.201345] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.205374] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Journal Service... [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.044335] device-mapper: uevent: version 1.0.3 [ 5.046840] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.034598] virtio_net virtio0 ens2: renamed from eth0 [ 6.096405] scsi host0: ata_piix [ 6.119643] scsi host1: ata_piix [ 6.121047] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.123229] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.607827] random: fast init done [ 10.374394] random: crng init done [ 10.376338] random: 7 urandom warning(s) missed due to ratelimiting [ 10.918536] dracut-initqueue[593]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 11.711328] 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 13.183743] printk: systemd: 24 output lines suppressed due to ratelimiting [ 13.528520] SELinux: Disabled at runtime. [ 13.586614] 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) [ 13.595207] systemd[1]: Detected virtualization kvm. [ 13.597036] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 14.351450] systemd[1]: initrd-switch-root.service: Succeeded. [ 14.356753] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 14.364578] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 14.368538] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 14.372387] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 14.389104] systemd[1]: Starting Journal Service... Starting Journal Service... [ 14.397969] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Created slice User and Session Slice. [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ 14.559793] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Forward Password Requests to Wall Directory Watch. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Reached target Slices. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. [ OK ] Started Journal Service. [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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 15.127464] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 15.541406] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 15.597764] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 15.835392] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 15.928890] EDAC sbridge: Ver: 1.1.2 [ 17.358198] Key type dns_resolver registered [ 17.696572] NFS: Registering the id_resolver key type [ 17.698434] Key type id_resolver registered [ 17.699908] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started RPC Bind. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg258-client login: [ 65.480889] libcfs: loading out-of-tree module taints kernel. [ 65.572257] Key type ._llcrypt registered [ 65.579192] Key type .llcrypt registered [ 65.993721] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 66.011876] alg: No test for adler32 (adler32-zlib) [ 67.473765] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 68.287787] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 70.023123] Key type lgssc registered [ 71.697767] Lustre: Echo OBD driver; http://www.lustre.org/ [ 174.390199] Lustre: Mounted lustre-client [ 178.774487] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 179.846076] hrtimer: interrupt took 6067381 ns [ 194.080400] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing check_logdir /tmp/testlogs/ [ 197.792951] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing yml_node [ 200.162223] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 23s idle [ 201.643908] Lustre: DEBUG MARKER: Client: 2.16.61.43 [ 204.015433] Lustre: DEBUG MARKER: MDS: 2.16.61.43 [ 206.136873] Lustre: DEBUG MARKER: OSS: 2.16.61.43 [ 207.615351] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 17:30:54 EST 2025 [ 220.865764] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 222.122286] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 223.440307] Lustre: DEBUG MARKER: === sanityn: start setup 17:31:10 (1763332270) === [ 223.994980] Lustre: Mounted lustre-client [ 227.108742] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing check_config_client /mnt/lustre [ 243.357526] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 254.241693] Lustre: DEBUG MARKER: === sanityn: finish setup 17:31:41 (1763332301) === [ 256.310110] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 17:31:43 (1763332303) [ 264.703761] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 17:31:51 (1763332311) [ 271.512762] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 17:31:58 (1763332318) [ 277.891946] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 17:32:05 (1763332325) [ 284.221365] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 17:32:11 (1763332331) [ 290.180497] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 17:32:17 (1763332337) [ 295.809699] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 17:32:23 (1763332343) [ 301.613720] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 17:32:28 (1763332348) [ 303.047581] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 304.930665] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 17:32:32 (1763332352) [ 310.789584] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 17:32:38 (1763332358) [ 315.576275] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 17:32:43 (1763332363) [ 322.318139] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 17:32:49 (1763332369) [ 326.628933] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 21s idle [ 326.631772] Lustre: Skipped 1 previous similar message [ 326.927395] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 17:32:54 (1763332374) [ 333.079744] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 17:33:00 (1763332380) [ 336.866488] Lustre: lustre-OST0001-osc-ffff9f02031ec000: disconnect after 20s idle [ 338.728434] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 17:33:06 (1763332386) [ 343.881462] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 17:33:11 (1763332391) [ 350.804346] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 17:33:18 (1763332398) [ 356.137592] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 17:33:23 (1763332403) [ 362.887951] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 17:33:30 (1763332410) [ 368.304657] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 17:33:35 (1763332415) [ 373.881631] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 17:33:41 (1763332421) [ 374.344165] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 521.792419] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 17:36:09 (1763332569) [ 528.247412] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 17:36:15 (1763332575) [ 533.416907] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 17:36:20 (1763332580) [ 538.693467] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 17:36:26 (1763332586) [ 543.742522] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 17:36:31 (1763332591) [ 547.575836] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 17:36:35 (1763332595) [ 548.997547] Lustre: DEBUG MARKER: chmod [ 553.261559] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 17:36:40 (1763332600) [ 580.353142] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7524352KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 592.767387] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 17:37:20 (1763332640) [ 637.524885] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 17:38:04 (1763332684) [ 664.615599] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 17:38:31 (1763332711) [ 667.548634] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 669.724782] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 17:38:36 (1763332716) [ 710.624374] Lustre: lustre-OST0001-osc-ffff9f02031ec000: disconnect after 21s idle [ 713.891765] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 17:39:21 (1763332761) [ 720.470211] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 17:39:27 (1763332767) [ 721.622522] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 721.719155] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 721.807758] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 721.846557] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 721.899113] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 721.984789] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.069780] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.146875] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.233856] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.317622] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.444586] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.522850] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.588635] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.676949] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.764057] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 722.892447] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.011197] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.067081] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.197540] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.254234] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.360538] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.449287] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.519980] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.592342] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.675798] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.760651] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.850501] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.931126] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 723.992802] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.049265] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.122161] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.181852] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.258220] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.327796] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.368980] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.403741] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.488769] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.557701] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.601724] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.659546] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.752153] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.835195] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.887671] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 724.949678] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.031150] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.137132] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.197257] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.237057] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.301356] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.357779] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.407744] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.449807] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.509704] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.592338] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.705497] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.853916] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 725.956372] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.023700] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.107168] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.152055] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.187086] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.236265] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.326027] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.411113] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.503555] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.579899] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.686497] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.791400] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.848851] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 726.936234] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.059473] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.159956] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.243107] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.320723] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.425996] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.523718] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.570520] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.615341] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.665079] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.731249] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.779560] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.863544] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.927507] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 727.993778] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.044274] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.109770] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.159462] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.189958] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.254379] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.336093] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.427207] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.519945] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.583412] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.693282] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.768037] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.841248] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.894826] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 728.982965] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.086068] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.187271] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.247538] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.335383] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.415349] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.465911] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.506501] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.553733] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.617370] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.671773] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.734526] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.791703] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.854464] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 729.944530] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.003644] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.101598] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.172913] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.281817] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.344569] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.404648] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.500391] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.609475] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.690592] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.761873] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.851158] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.924957] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 730.983235] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.073795] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.198428] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.277916] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.380917] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.459907] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.527964] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.625916] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.736028] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.825466] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 731.921896] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.005026] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.069651] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.167730] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.226169] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.292173] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.410356] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.486734] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.565602] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.647252] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.712962] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.795682] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.839852] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.897272] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 732.962732] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.030970] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.103665] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.149671] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.225161] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.310559] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.401410] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.467729] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.531942] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.591432] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.651330] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.722653] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.812561] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.871996] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.918318] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 733.961864] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.032338] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.107113] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.208642] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.296282] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.372269] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.478459] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.565833] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.636498] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.701778] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.777064] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.856846] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 734.954513] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.057528] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.176150] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.274967] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.372740] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.476728] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.590765] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.675656] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.757312] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.854594] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 735.909895] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.015439] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.112581] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.217338] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.224120] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 21s idle [ 736.300405] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.381835] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.438814] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.512793] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.616691] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.670719] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.753958] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.836546] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 736.910487] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.021583] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.122117] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.232909] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.317148] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.379608] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.454890] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.514895] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.603833] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.681415] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.765797] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.852362] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.904453] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 737.976321] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.105599] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.260061] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.360264] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.462927] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.531715] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.649541] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.748058] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.867688] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 738.926640] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.053724] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.174241] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.287245] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.361952] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.484786] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.564527] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.651079] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.718860] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.822969] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 739.926687] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.009303] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.097074] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.143212] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.227548] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.331617] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.378415] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.464324] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.574985] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.669644] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 740.756924] rw_seq_cst_vs_d (29472): drop_caches: 3 [ 747.483303] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 17:39:54 (1763332794) [ 747.937613] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.054320] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.240077] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.275272] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.377638] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.419567] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.525719] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.644554] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.673453] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.722614] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.757487] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.885594] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 748.919649] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.030320] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.184795] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.318877] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.439448] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.481853] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.504810] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.542976] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.575742] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.602954] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.755473] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.883490] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 749.935835] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.023970] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.074443] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.243723] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.321915] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.505542] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.588777] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.704396] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.765895] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.870411] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.901390] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 750.996640] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.038435] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.081822] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.212085] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.371515] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.642376] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.736705] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.778290] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.898092] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 751.984289] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.065142] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.122587] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.161172] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.247051] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.388240] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.426909] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.497729] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.641314] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.776429] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 752.824529] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.001503] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.183896] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.275505] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.346482] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.456195] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.503865] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.557122] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.594300] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.785995] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.814676] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 753.906196] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.027061] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.105371] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.144022] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.180688] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.281068] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.320158] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.486703] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.638492] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.774874] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.857264] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 754.942706] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.078941] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.198169] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.294318] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.333553] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.400703] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.460928] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.569658] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.631672] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.822490] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 755.947314] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.049362] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.119270] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.429735] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.626947] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.646043] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.715206] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.781582] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.830564] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 756.930215] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.018972] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.120687] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.189225] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.269343] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.412408] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.473913] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.554570] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.638965] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.708035] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.890319] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 757.965293] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.210631] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.398661] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.548220] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.687297] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.798326] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.828887] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.862188] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 758.997596] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.032994] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.118829] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.162640] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.274866] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.302390] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.414788] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.464489] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.526455] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.567251] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.619951] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.693605] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.770555] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.934331] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.954153] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.973690] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 759.992517] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.011934] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.029843] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.048607] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.074380] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.182595] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.234676] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.352334] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.496273] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.587321] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.655955] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.763906] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.885287] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.943349] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 760.989223] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.123612] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.193758] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.330827] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.460207] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.512058] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.545203] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.641452] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.670247] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.784089] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 761.881110] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.007579] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.203391] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.317780] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.431293] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.501066] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.547702] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.657084] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.743730] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.824725] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 762.938492] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.003765] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.143549] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.201744] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.263620] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.404505] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.479937] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.697039] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.765419] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.874585] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 763.974702] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.052058] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.171350] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.416782] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.700939] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.750378] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 764.897863] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.015710] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.086838] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.229773] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.288560] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.374730] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.508926] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.628690] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.690824] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.747037] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 765.997942] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.049839] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.135653] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.179138] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.203804] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.338895] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.386359] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.430501] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.620307] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.741547] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.814617] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 766.947032] Lustre: lustre-OST0001-osc-ffff9f020524a800: disconnect after 21s idle [ 766.949919] Lustre: Skipped 1 previous similar message [ 766.983145] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.213635] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.245334] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.317710] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.411445] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.462187] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.488841] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.600928] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 767.644575] rw_seq_cst_vs_d (30048): drop_caches: 3 [ 774.836846] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 17:40:22 (1763332822) [ 781.560418] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 17:40:28 (1763332828) [ 789.001891] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 17:40:36 (1763332836) [ 830.637949] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 17:41:17 (1763332877) [ 831.199355] bash (32496): drop_caches: 3 [ 834.402317] bash (32496): drop_caches: 3 [ 837.844206] bash (32496): drop_caches: 3 [ 841.547183] bash (32496): drop_caches: 3 [ 844.722813] bash (32496): drop_caches: 3 [ 847.859904] bash (32496): drop_caches: 3 [ 851.005554] bash (32496): drop_caches: 3 [ 854.126405] bash (32496): drop_caches: 3 [ 857.261631] bash (32496): drop_caches: 3 [ 860.450587] bash (32496): drop_caches: 3 [ 863.585595] bash (32496): drop_caches: 3 [ 866.726741] bash (32496): drop_caches: 3 [ 869.909493] bash (32496): drop_caches: 3 [ 873.069264] bash (32496): drop_caches: 3 [ 877.577622] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 17:42:04 (1763332924) [ 887.117680] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 17:42:14 (1763332934) [ 900.064729] Lustre: lustre-OST0001-osc-ffff9f02031ec000: disconnect after 22s idle [ 900.077933] Lustre: Skipped 1 previous similar message [ 929.124590] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 17:42:56 (1763332976) [ 931.975105] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 933.540531] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 17:43:00 (1763332980) [ 939.888863] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 17:43:07 (1763332987) [ 941.025835] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 21s idle [ 945.410320] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 17:43:12 (1763332992) [ 1013.116254] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 17:44:20 (1763333060) [ 1017.962704] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 17:44:25 (1763333065) [ 1023.047527] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 17:44:30 (1763333070) [ 1029.061782] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 17:44:36 (1763333076) [ 1030.258196] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1031.648503] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 17:44:39 (1763333079) [ 1037.913043] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 17:44:45 (1763333085) [ 1038.307503] Lustre: lustre-OST0000-osc-ffff9f02031ec000: disconnect after 20s idle [ 1038.316040] Lustre: Skipped 4 previous similar messages [ 1045.272266] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 17:44:52 (1763333092) [ 1051.978399] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1053.348647] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 17:45:00 (1763333100) [ 1061.179301] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 17:45:08 (1763333108) [ 1061.501698] Lustre: *** cfs_fail_loc=314, val=0*** [ 1062.561830] Lustre: *** cfs_fail_loc=314, val=0*** [ 1062.563191] Lustre: Skipped 2 previous similar messages [ 1067.908435] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 17:45:15 (1763333115) [ 1080.035209] Lustre: *** cfs_fail_loc=314, val=0*** [ 1084.399036] Lustre: lustre-OST0000-osc-ffff9f02031ec000: Connection to lustre-OST0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1084.418294] LustreError: lustre-OST0000-osc-ffff9f02031ec000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1084.429528] Lustre: lustre-OST0000-osc-ffff9f02031ec000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 1085.176353] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 17:45:32 (1763333132) [ 1085.375173] LustreError: 42320:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1088.423169] LustreError: 42320:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1093.289657] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 17:45:40 (1763333140) [ 1099.132175] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 17:45:46 (1763333146) [ 1104.181063] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1105.337880] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 17:45:53 (1763333153) [ 1106.685754] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1108.065361] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 17:45:55 (1763333155) [ 1109.217056] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1110.552025] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 17:45:58 (1763333158) [ 1111.685540] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1113.293868] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 17:46:00 (1763333160) [ 1114.792133] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1116.281449] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 17:46:03 (1763333163) [ 1170.315363] Lustre: lustre-OST0000-osc-ffff9f02031ec000: Connection to lustre-OST0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1170.336268] LustreError: lustre-OST0000-osc-ffff9f02031ec000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1170.350232] Lustre: lustre-OST0000-osc-ffff9f02031ec000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 1170.355689] LustreError: lustre-OST0000-osc-ffff9f020524a800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1186.797159] Lustre: lustre-OST0001-osc-ffff9f020524a800: Connection to lustre-OST0001 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1186.804160] Lustre: Skipped 1 previous similar message [ 1186.819764] LustreError: lustre-OST0001-osc-ffff9f020524a800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1186.827801] Lustre: lustre-OST0001-osc-ffff9f020524a800: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 1186.834158] Lustre: Skipped 1 previous similar message [ 1191.904184] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 22s idle [ 1203.033937] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1204.256288] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid in IDLE state after 0 sec [ 1206.738121] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1207.782110] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid in FULL state after 0 sec [ 1211.456280] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1212.541519] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid in IDLE state after 0 sec [ 1215.194831] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1216.177568] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid in FULL state after 0 sec [ 1222.517745] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1223.481939] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9f02031ec000.ost_server_uuid in IDLE state after 0 sec [ 1226.011249] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid 50 [ 1227.213301] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9f02031ec000.ost_server_uuid in FULL state after 0 sec [ 1228.430152] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 17:47:56 (1763333276) [ 1230.386378] Lustre: DEBUG MARKER: Race attempt 0 [ 1232.565613] Lustre: DEBUG MARKER: Wait for 48942 49032 for 60 sec... [ 1297.569454] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 17:49:05 (1763333345) [ 1304.450321] Lustre: DEBUG MARKER: start test - cycle (0) [ 1327.358925] Lustre: DEBUG MARKER: start test - cycle (1) [ 1354.056073] Lustre: DEBUG MARKER: start test - cycle (2) [ 1378.495828] Lustre: DEBUG MARKER: start test - cycle (3) [ 1403.620675] Lustre: DEBUG MARKER: start test - cycle (4) [ 1430.383914] Lustre: DEBUG MARKER: start test - cycle (5) [ 1455.976676] Lustre: DEBUG MARKER: start test - cycle (6) [ 1458.147363] Lustre: lustre-OST0000-osc-ffff9f02031ec000: disconnect after 21s idle [ 1458.150236] Lustre: Skipped 5 previous similar messages [ 1481.360176] Lustre: DEBUG MARKER: start test - cycle (7) [ 1506.873171] Lustre: DEBUG MARKER: start test - cycle (8) [ 1532.253501] Lustre: DEBUG MARKER: start test - cycle (9) [ 1557.649513] Lustre: DEBUG MARKER: start test - cycle (10) [ 1587.941763] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 17:53:55 (1763333635) [ 1658.598437] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 17:55:06 (1763333706) [ 1664.715666] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 17:55:12 (1763333712) [ 1671.681558] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 17:55:19 (1763333719) [ 1678.420821] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 17:55:26 (1763333726) [ 1678.764813] Lustre: *** cfs_fail_loc=411, val=0*** [ 1683.993354] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1685.547452] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 17:55:32 (1763333732) [ 1702.163790] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 17:55:49 (1763333749) [ 1718.665083] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 17:56:05 (1763333765) [ 1733.892717] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 17:56:21 (1763333781) [ 1749.312439] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 17:56:36 (1763333796) [ 1760.231827] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 17:56:47 (1763333807) [ 1770.931346] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 17:56:58 (1763333818) [ 1780.989678] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 17:57:08 (1763333828) [ 1791.662890] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 17:57:19 (1763333839) [ 1801.655609] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 17:57:29 (1763333849) [ 1811.028431] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 17:57:38 (1763333858) [ 1821.753988] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 17:57:49 (1763333869) [ 1833.136560] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 17:58:00 (1763333880) [ 2451.427586] Lustre: lustre-OST0000-osc-ffff9f02031ec000: disconnect after 21s idle [ 2451.430233] Lustre: Skipped 14 previous similar messages [ 2786.831775] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:13:54 (1763334834) [ 2797.006454] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:14:04 (1763334844) [ 2805.995748] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:14:13 (1763334853) [ 2815.453106] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:14:23 (1763334863) [ 2825.624473] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:14:33 (1763334873) [ 2836.255673] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:14:43 (1763334883) [ 2846.507029] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:14:54 (1763334894) [ 2856.171165] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:15:03 (1763334903) [ 2866.453684] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:15:13 (1763334913) [ 2921.179487] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:16:08 (1763334968) [ 2933.498956] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:16:21 (1763334981) [ 2943.750533] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:16:31 (1763334991) [ 2953.406662] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:16:41 (1763335001) [ 2962.753984] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 18:16:50 (1763335010) [ 2972.771862] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 18:17:00 (1763335020) [ 2983.361335] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 18:17:10 (1763335030) [ 2995.007551] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 18:17:22 (1763335042) [ 2996.277688] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2997.744655] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 18:17:25 (1763335045) [ 3096.339694] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 18:19:03 (1763335143) [ 3168.223321] Lustre: lustre-OST0001-osc-ffff9f020524a800: disconnect after 22s idle [ 3168.229375] Lustre: Skipped 6 previous similar messages [ 3975.000203] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 18:33:42 (1763336022) [ 3982.304189] Lustre: lustre-OST0000-osc-ffff9f02031ec000: disconnect after 20s idle [ 3982.313769] Lustre: Skipped 8 previous similar messages [ 3984.657145] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 18:33:52 (1763336032) [ 3994.845820] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 18:34:02 (1763336042) [ 4005.353590] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 18:34:12 (1763336052) [ 4015.359360] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 18:34:22 (1763336062) [ 4026.622248] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 18:34:33 (1763336073) [ 4037.898855] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 18:34:45 (1763336085) [ 4048.328706] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 18:34:55 (1763336095) [ 4058.631200] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 18:35:06 (1763336106) [ 4059.958074] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4061.366812] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 18:35:08 (1763336108) [ 4169.225505] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 18:36:56 (1763336216) [ 4181.018972] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 18:37:08 (1763336228) [ 4193.813353] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 18:37:20 (1763336240) [ 4206.495079] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 18:37:33 (1763336253) [ 4217.768198] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 18:37:45 (1763336265) [ 4229.802887] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 18:37:57 (1763336277) [ 4240.910569] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 18:38:08 (1763336288) [ 4250.202384] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 18:38:17 (1763336297) [ 4251.356092] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4252.666779] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 18:38:20 (1763336300) [ 4612.063391] Lustre: lustre-OST0001-osc-ffff9f020524a800: disconnect after 22s idle [ 4612.068393] Lustre: Skipped 9 previous similar messages [ 5168.146836] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 18:53:35 (1763337215) [ 5179.063580] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 18:53:46 (1763337226) [ 5190.651112] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 18:53:58 (1763337238) [ 5201.637203] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 18:54:09 (1763337249) [ 5211.642205] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 18:54:19 (1763337259) [ 5222.087338] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 18:54:29 (1763337269) [ 5233.000807] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 18:54:40 (1763337280) [ 5244.119306] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 18:54:51 (1763337291) [ 5254.453668] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 18:55:02 (1763337302) [ 5255.829926] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5256.886859] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 18:55:04 (1763337304) [ 5258.034980] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5259.391892] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 18:55:06 (1763337306) [ 5260.565712] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5262.045747] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 18:55:09 (1763337309) [ 5262.307691] Lustre: lustre-OST0001-osc-ffff9f020524a800: disconnect after 22s idle [ 5262.310072] Lustre: Skipped 4 previous similar messages [ 5263.080874] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5264.088392] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 18:55:11 (1763337311) [ 5264.916132] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5266.063497] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 18:55:13 (1763337313) [ 5266.918752] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5267.974838] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 18:55:15 (1763337315) [ 5268.950135] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5269.974584] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 18:55:17 (1763337317) [ 5271.004429] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5272.271370] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 18:55:19 (1763337319) [ 5272.556606] LustreError: 21356:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5274.639371] LustreError: 21356:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5281.899094] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 18:55:29 (1763337329) [ 5289.368936] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 18:55:36 (1763337336) [ 5289.734789] LustreError: 216682:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5293.802198] LustreError: 216682:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5293.826417] LustreError: 216682:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5297.903266] LustreError: 216682:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5297.927846] LustreError: 216688:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5301.992202] LustreError: 216688:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5307.664626] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 18:55:54 (1763337354) [ 5319.146977] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 18:56:06 (1763337366) [ 5325.749894] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 18:56:13 (1763337373) [ 5332.143576] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 18:56:19 (1763337379) [ 5360.896070] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 18:56:48 (1763337408) [ 5372.049800] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 18:56:59 (1763337419) [ 5382.922226] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 18:57:10 (1763337430) [ 5400.067492] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 18:57:27 (1763337447) [ 5414.853704] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 18:57:42 (1763337462) [ 5416.201879] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5417.630221] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 18:57:45 (1763337465) [ 5435.578547] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 18:58:03 (1763337483) [ 5457.040929] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 18:58:24 (1763337504) [ 5555.068955] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 19:00:02 (1763337602) [ 5658.455313] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 19:01:45 (1763337705) [ 5666.038623] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5673.350968] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 19:02:00 (1763337720) [ 5681.805532] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 19:02:09 (1763337729) [ 5684.201633] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5685.905196] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 19:02:13 (1763337733) [ 5687.575307] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5689.008162] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 19:02:16 (1763337736) [ 5690.380538] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 5692.078436] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 19:02:19 (1763337739) [ 5693.611242] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5695.202286] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 19:02:22 (1763337742) [ 5701.430326] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 19:02:28 (1763337748) [ 5708.387871] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 19:02:35 (1763337755) [ 5711.763390] LustreError: lustre-MDT0000-mdc-ffff9f020524a800: operation ldlm_enqueue to node 192.168.202.158@tcp failed: rc = -35 [ 5717.886362] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 19:02:45 (1763337765) [ 5718.324343] LustreError: 2373:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5720.408206] LustreError: 2373:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5728.895851] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 19:02:56 (1763337776) [ 5784.280642] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 19:03:51 (1763337831) [ 5790.708504] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 19:03:58 (1763337838) [ 5801.145042] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 19:04:08 (1763337848) [ 5814.233795] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 19:04:21 (1763337861) [ 5827.353228] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 19:04:34 (1763337874) [ 5849.435362] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 19:04:56 (1763337896) [ 5871.399346] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 19:05:18 (1763337918) [ 5883.433933] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 19:05:30 (1763337930) [ 5892.075543] Lustre: lustre-OST0000-osc-ffff9f020524a800: disconnect after 22s idle [ 5892.078176] Lustre: Skipped 6 previous similar messages [ 5895.916051] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 19:05:43 (1763337943) [ 5918.641716] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 19:06:05 (1763337965) [ 5980.226425] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 19:07:07 (1763338027) [ 6124.729711] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 19:09:32 (1763338172) [ 6272.476633] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 19:11:59 (1763338319) [ 6496.223274] Lustre: lustre-OST0001-osc-ffff9f020524a800: disconnect after 20s idle [ 6496.247995] Lustre: Skipped 16 previous similar messages [ 6669.438264] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 19:18:36 (1763338716) [ 6716.678558] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 19:19:24 (1763338764) [ 6761.655408] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 19:20:09 (1763338809) [ 6800.053203] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 19:20:47 (1763338847) [ 6887.751710] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 19:22:15 (1763338935) [ 6961.121377] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 19:23:28 (1763339008) [ 7092.995777] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 19:25:40 (1763339140) [ 7095.781961] LustreError: 256146:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f020524a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7095.810181] LustreError: 256146:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7095.862082] Lustre: Unmounted lustre-client [ 7097.447770] LustreError: 256159:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02031ec000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7097.467148] LustreError: 256159:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7097.479249] LustreError: 256159:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7097.482904] LustreError: 256159:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 7097.509151] Lustre: Unmounted lustre-client [ 7159.057113] Lustre: Mounted lustre-client [ 7161.741139] Lustre: Mounted lustre-client [ 7164.688644] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7182.304468] Lustre: lustre-OST0000-osc-ffff9f02197ca000: disconnect after 20s idle [ 7182.306977] Lustre: Skipped 18 previous similar messages [ 7271.383840] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 19:28:38 (1763339318) [ 7289.269744] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 19:28:56 (1763339336) [ 7299.201500] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 19:29:06 (1763339346) [ 7356.412032] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 19:30:03 (1763339403) [ 7437.877368] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 19:31:25 (1763339485) [ 7449.626973] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 19:31:37 (1763339497) [ 7546.790729] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 19:33:13 (1763339593) [ 7579.972550] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 19:33:47 (1763339627) [ 7631.699523] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 19:34:38 (1763339678) [ 7639.445617] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 19:34:46 (1763339686) [ 7657.592703] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 19:35:04 (1763339704) [ 7658.875467] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 7660.684940] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 19:35:07 (1763339707) [ 7662.182712] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 7663.571600] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 19:35:11 (1763339711) [ 7665.043697] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 7666.703635] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 19:35:14 (1763339714) [ 7668.039710] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 7669.663411] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 19:35:17 (1763339717) [ 7671.305625] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7673.006748] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 19:35:20 (1763339720) [ 7800.506391] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 19:37:27 (1763339847) [ 7806.854083] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 19:37:34 (1763339854) [ 7808.124309] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 7809.590967] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 19:37:36 (1763339856) [ 7817.184674] Lustre: lustre-OST0001-osc-ffff9f02197ca000: disconnect after 22s idle [ 7817.187553] Lustre: Skipped 12 previous similar messages [ 7821.876271] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 19:37:49 (1763339869) [ 7831.474972] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 19:37:58 (1763339878) [ 7832.899938] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 7834.558254] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 19:38:01 (1763339881) [ 7835.924908] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 7837.463502] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 19:38:04 (1763339884) [ 7839.121526] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 7840.954360] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 19:38:08 (1763339888) [ 7857.000315] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 19:38:24 (1763339904) [ 7857.349976] Lustre: DEBUG MARKER: write [ 7857.393452] LustreError: 258241:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7859.401279] Lustre: DEBUG MARKER: kill 282401 [ 7859.410982] LustreError: 282401:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7862.415141] LustreError: 258241:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7865.415421] LustreError: 282401:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7872.982543] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 19:38:40 (1763339920) [ 7875.655781] LustreError: 283011:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7885.679155] LustreError: 283011:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7892.942824] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 19:39:00 (1763339940) [ 7893.498472] LustreError: 283591:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7895.583153] LustreError: 283591:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [ 7906.054933] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 19:39:13 (1763339953) [ 7907.654534] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7909.533582] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 19:39:16 (1763339956) [ 7917.217529] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 19:39:24 (1763339964) [ 7925.003637] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 19:39:32 (1763339972) [ 7933.033145] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 19:39:40 (1763339980) [ 7940.038288] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 19:39:47 (1763339987) [ 7946.289669] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 19:39:53 (1763339993) [ 7952.530535] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 19:39:59 (1763339999) [ 7959.843266] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 7961.521394] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 19:40:08 (1763340008) [ 7962.901620] Lustre: *** cfs_fail_loc=415, val=0*** [ 7973.255591] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 19:40:20 (1763340020) [ 7974.395086] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 7975.943359] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 19:40:23 (1763340023) [ 7976.304431] LustreError: 258241:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7976.317662] LustreError: 258241:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7981.311133] LustreError: 258241:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7991.359112] LustreError: 258241:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7991.373224] LustreError: 258241:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 8003.107592] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 19:40:50 (1763340050) [ 8004.807504] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 8006.777681] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 19:40:53 (1763340053) [ 8013.539611] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 19:41:00 (1763340060) [ 8018.682894] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 19:41:06 (1763340066) [ 8025.550895] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 19:41:13 (1763340073) [ 8037.218410] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 19:41:24 (1763340084) [ 8037.749252] LustreError: 246046:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8037.754691] LustreError: 246046:0:(osc_request.c:2944:osc_build_rpc()) Skipped 5 previous similar messages [ 8041.815128] LustreError: 246046:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8041.818364] LustreError: 246046:0:(osc_request.c:2944:osc_build_rpc()) Skipped 2 previous similar messages [ 8047.759340] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 19:41:35 (1763340095) [ 8050.127671] LustreError: 293544:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0240e77000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8050.142290] LustreError: 293544:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8050.152807] LustreError: 293544:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8050.157722] LustreError: 293544:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8050.203414] Lustre: Unmounted lustre-client [ 8051.432798] LustreError: 293564:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02197ca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8051.441488] LustreError: 293564:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8051.458178] LustreError: 293564:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8051.463015] LustreError: 293564:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 8051.500494] Lustre: Unmounted lustre-client [ 8052.799798] Lustre: DEBUG MARKER: Iteration 1 [ 8053.001447] LustreError: 293723:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8053.004824] LustreError: 293731:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8053.009086] LustreError: 293723:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8053.196126] Lustre: Mounted lustre-client [ 8054.330320] LustreError: 293830:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02197ca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8054.341980] LustreError: 293830:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8054.364407] LustreError: 293830:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8054.367455] LustreError: 293830:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8054.405575] Lustre: Unmounted lustre-client [ 8056.929275] Key type lgssc unregistered [ 8057.173070] LNet: 294073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8057.178882] LNetError: 294073:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8057.198321] LNet: Removed LNI 192.168.202.58@tcp [ 8057.823355] Key type .llcrypt unregistered [ 8057.827767] Key type ._llcrypt unregistered [ 8058.454391] Key type ._llcrypt registered [ 8058.455877] Key type .llcrypt registered [ 8058.959315] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8058.980393] alg: No test for adler32 (adler32-zlib) [ 8060.421174] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8061.286642] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8062.999176] Key type lgssc registered [ 8064.265719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8075.544972] Lustre: DEBUG MARKER: Iteration 2 [ 8075.844353] LustreError: 294893:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8075.847719] LustreError: 294894:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8075.852237] LustreError: 294893:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8077.185419] Lustre: Mounted lustre-client [ 8077.192251] Lustre: Skipped 1 previous similar message [ 8078.696019] LustreError: 295001:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211df4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8078.730514] LustreError: 295001:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8078.777389] Lustre: Unmounted lustre-client [ 8081.630489] Key type lgssc unregistered [ 8081.923627] LNet: 295245:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8081.936056] LNetError: 295245:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8082.983417] LNet: Removed LNI 192.168.202.58@tcp [ 8083.622162] Key type .llcrypt unregistered [ 8083.623474] Key type ._llcrypt unregistered [ 8084.485687] Key type ._llcrypt registered [ 8084.494627] Key type .llcrypt registered [ 8085.054674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8085.083228] alg: No test for adler32 (adler32-zlib) [ 8086.154113] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8086.364719] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8088.039169] Key type lgssc registered [ 8089.252330] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8099.788456] Lustre: DEBUG MARKER: Iteration 3 [ 8100.145976] LustreError: 296064:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8100.152330] LustreError: 296063:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8100.161534] LustreError: 296064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 8101.469575] Lustre: Mounted lustre-client [ 8102.920635] LustreError: 296165:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203061800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8102.963783] LustreError: 296165:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8103.002129] Lustre: Unmounted lustre-client [ 8105.791771] Key type lgssc unregistered [ 8106.105108] LNet: 296414:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8106.113959] LNetError: 296414:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8106.130472] LNet: Removed LNI 192.168.202.58@tcp [ 8106.943387] Key type .llcrypt unregistered [ 8106.945135] Key type ._llcrypt unregistered [ 8107.784775] Key type ._llcrypt registered [ 8107.789875] Key type .llcrypt registered [ 8108.360060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8108.375819] alg: No test for adler32 (adler32-zlib) [ 8109.417495] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8109.722419] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8111.431754] Key type lgssc registered [ 8112.666727] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8123.453939] Lustre: DEBUG MARKER: Iteration 4 [ 8123.928138] LustreError: 297233:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8123.928405] LustreError: 297239:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8123.941489] LustreError: 297233:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8125.185558] Lustre: Mounted lustre-client [ 8125.195696] Lustre: Skipped 1 previous similar message [ 8126.707472] LustreError: 297345:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02215d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8126.721159] LustreError: 297345:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8126.725460] LustreError: 297344:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8126.743926] LustreError: 297344:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8126.796899] Lustre: Unmounted lustre-client [ 8129.077903] Key type lgssc unregistered [ 8129.287539] LNet: 297584:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8129.294853] LNetError: 297584:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8129.316185] LNet: Removed LNI 192.168.202.58@tcp [ 8129.814328] Key type .llcrypt unregistered [ 8129.817118] Key type ._llcrypt unregistered [ 8130.443523] Key type ._llcrypt registered [ 8130.452205] Key type .llcrypt registered [ 8130.924024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8130.940840] alg: No test for adler32 (adler32-zlib) [ 8132.001522] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8132.206146] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8133.895251] Key type lgssc registered [ 8135.137728] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8145.262063] Lustre: DEBUG MARKER: Iteration 5 [ 8145.592187] LustreError: 298405:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8145.592675] LustreError: 298406:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8145.605378] LustreError: 298405:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8146.821377] Lustre: Mounted lustre-client [ 8146.827221] Lustre: Skipped 1 previous similar message [ 8147.994759] LustreError: 298509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f021213b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8148.025056] LustreError: 298509:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8148.083209] Lustre: Unmounted lustre-client [ 8150.925489] Key type lgssc unregistered [ 8151.293757] LNet: 298755:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8151.311240] LNetError: 298755:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8151.325201] LNet: Removed LNI 192.168.202.58@tcp [ 8152.040189] Key type .llcrypt unregistered [ 8152.041826] Key type ._llcrypt unregistered [ 8152.807562] Key type ._llcrypt registered [ 8152.809168] Key type .llcrypt registered [ 8153.279541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8153.306872] alg: No test for adler32 (adler32-zlib) [ 8154.476298] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8154.781651] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8156.449214] Key type lgssc registered [ 8157.748450] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8168.620872] Lustre: DEBUG MARKER: Iteration 6 [ 8169.018258] LustreError: 299573:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8169.025848] LustreError: 299572:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8169.039510] LustreError: 299573:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8170.510888] Lustre: Mounted lustre-client [ 8172.125367] LustreError: 299677:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203063800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8172.137807] LustreError: 299677:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8172.145683] LustreError: 299685:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8172.204607] Lustre: Unmounted lustre-client [ 8174.920154] Key type lgssc unregistered [ 8175.137735] LNet: 299925:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8175.142907] LNetError: 299925:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8175.162736] LNet: Removed LNI 192.168.202.58@tcp [ 8175.746979] Key type .llcrypt unregistered [ 8175.749401] Key type ._llcrypt unregistered [ 8176.302398] Key type ._llcrypt registered [ 8176.304660] Key type .llcrypt registered [ 8176.971956] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8176.988498] alg: No test for adler32 (adler32-zlib) [ 8178.032270] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8178.342816] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8180.047191] Key type lgssc registered [ 8181.323949] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8191.011824] Lustre: DEBUG MARKER: Iteration 7 [ 8191.294747] LustreError: 300744:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8191.296750] LustreError: 300745:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8191.311738] LustreError: 300744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8192.542186] Lustre: Mounted lustre-client [ 8193.939613] LustreError: 300850:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02119b4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8193.979756] LustreError: 300850:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8194.026210] Lustre: Unmounted lustre-client [ 8196.451356] Key type lgssc unregistered [ 8196.702072] LNet: 301098:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8196.710925] LNetError: 301098:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8196.726932] LNet: Removed LNI 192.168.202.58@tcp [ 8197.364140] Key type .llcrypt unregistered [ 8197.365419] Key type ._llcrypt unregistered [ 8198.299291] Key type ._llcrypt registered [ 8198.300949] Key type .llcrypt registered [ 8198.492133] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8198.513573] alg: No test for adler32 (adler32-zlib) [ 8199.533410] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8199.787248] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8201.495196] Key type lgssc registered [ 8202.579525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8212.725677] Lustre: DEBUG MARKER: Iteration 8 [ 8212.948116] LustreError: 301914:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8212.949688] LustreError: 301915:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8212.967939] LustreError: 301914:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [ 8214.141807] Lustre: Mounted lustre-client [ 8215.393442] LustreError: 302028:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0227280800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8215.407921] LustreError: 302028:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8215.413323] LustreError: 302025:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8215.450077] Lustre: Unmounted lustre-client [ 8218.523292] Key type lgssc unregistered [ 8218.762160] LNet: 302268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8218.771724] LNetError: 302268:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8218.788964] LNet: Removed LNI 192.168.202.58@tcp [ 8219.570182] Key type .llcrypt unregistered [ 8219.576214] Key type ._llcrypt unregistered [ 8220.750881] Key type ._llcrypt registered [ 8220.752867] Key type .llcrypt registered [ 8221.412627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8221.428540] alg: No test for adler32 (adler32-zlib) [ 8222.612988] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8222.931561] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8224.679976] Key type lgssc registered [ 8226.055888] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8236.150477] Lustre: DEBUG MARKER: Iteration 9 [ 8236.416675] LustreError: 303085:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8236.419913] LustreError: 303090:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8236.430578] LustreError: 303085:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8237.631301] Lustre: Mounted lustre-client [ 8238.841849] LustreError: 303192:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203ed8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8238.864106] LustreError: 303192:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8238.899454] Lustre: Unmounted lustre-client [ 8241.391599] Key type lgssc unregistered [ 8241.622858] LNet: 303435:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8241.626508] LNetError: 303435:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8241.640478] LNet: Removed LNI 192.168.202.58@tcp [ 8242.095172] Key type .llcrypt unregistered [ 8242.098408] Key type ._llcrypt unregistered [ 8242.810951] Key type ._llcrypt registered [ 8242.812454] Key type .llcrypt registered [ 8243.177563] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8243.185691] alg: No test for adler32 (adler32-zlib) [ 8244.149619] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8244.367399] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8246.017825] Key type lgssc registered [ 8247.231591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8257.521405] Lustre: DEBUG MARKER: Iteration 10 [ 8257.920409] LustreError: 304253:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8257.924555] LustreError: 304255:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8257.941976] LustreError: 304253:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8259.272881] Lustre: Mounted lustre-client [ 8260.490243] LustreError: 304365:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0207437800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8260.498696] LustreError: 304363:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8260.503677] LustreError: 304365:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8260.571080] Lustre: Unmounted lustre-client [ 8262.839362] Key type lgssc unregistered [ 8263.064452] LNet: 304605:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8263.074365] LNetError: 304605:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8263.099935] LNet: Removed LNI 192.168.202.58@tcp [ 8263.653307] Key type .llcrypt unregistered [ 8263.654929] Key type ._llcrypt unregistered [ 8264.526685] Key type ._llcrypt registered [ 8264.569036] Key type .llcrypt registered [ 8264.898193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8264.929061] alg: No test for adler32 (adler32-zlib) [ 8266.000074] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8266.308220] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8268.048784] Key type lgssc registered [ 8269.358390] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8279.759355] Lustre: DEBUG MARKER: Iteration 11 [ 8280.153843] LustreError: 305424:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8280.154913] LustreError: 305430:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8280.171129] LustreError: 305424:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [ 8281.348545] Lustre: Mounted lustre-client [ 8282.629768] LustreError: 305525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02197ca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8282.660471] LustreError: 305525:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8282.738849] Lustre: Unmounted lustre-client [ 8285.474309] Key type lgssc unregistered [ 8285.741430] LNet: 305772:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8285.758226] LNetError: 305772:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8285.772944] LNet: Removed LNI 192.168.202.58@tcp [ 8286.348301] Key type .llcrypt unregistered [ 8286.349898] Key type ._llcrypt unregistered [ 8287.009424] Key type ._llcrypt registered [ 8287.010976] Key type .llcrypt registered [ 8287.475399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8287.493672] alg: No test for adler32 (adler32-zlib) [ 8288.557768] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8288.779394] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8290.455166] Key type lgssc registered [ 8291.713631] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8302.367776] Lustre: DEBUG MARKER: Iteration 12 [ 8302.604305] LustreError: 306590:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8302.604336] LustreError: 306594:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8302.613840] LustreError: 306590:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8303.859341] Lustre: Mounted lustre-client [ 8303.872068] Lustre: Skipped 1 previous similar message [ 8305.260724] LustreError: 306698:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211df5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8305.282465] LustreError: 306698:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8305.317360] Lustre: Unmounted lustre-client [ 8308.276531] Key type lgssc unregistered [ 8308.500572] LNet: 306940:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8308.504715] LNetError: 306940:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8309.551976] LNet: Removed LNI 192.168.202.58@tcp [ 8310.347142] Key type .llcrypt unregistered [ 8310.349469] Key type ._llcrypt unregistered [ 8311.086774] Key type ._llcrypt registered [ 8311.087893] Key type .llcrypt registered [ 8311.529940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8311.543087] alg: No test for adler32 (adler32-zlib) [ 8312.619638] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8312.856513] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8314.535231] Key type lgssc registered [ 8315.885167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8325.945621] Lustre: DEBUG MARKER: Iteration 13 [ 8326.378172] LustreError: 307760:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8326.380222] LustreError: 307759:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8326.396831] LustreError: 307760:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8327.665704] Lustre: Mounted lustre-client [ 8328.800465] LustreError: 307865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203126000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8328.819747] LustreError: 307865:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8328.867109] Lustre: Unmounted lustre-client [ 8331.563105] Key type lgssc unregistered [ 8331.852372] LNet: 308111:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8331.859686] LNetError: 308111:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8331.887261] LNet: Removed LNI 192.168.202.58@tcp [ 8332.592377] Key type .llcrypt unregistered [ 8332.596394] Key type ._llcrypt unregistered [ 8333.221554] Key type ._llcrypt registered [ 8333.224714] Key type .llcrypt registered [ 8333.641624] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8333.650495] alg: No test for adler32 (adler32-zlib) [ 8334.649956] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8334.852813] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8336.591222] Key type lgssc registered [ 8337.767250] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8347.507886] Lustre: DEBUG MARKER: Iteration 14 [ 8347.956730] LustreError: 308930:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8347.958039] LustreError: 308931:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8347.973901] LustreError: 308930:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8349.173488] Lustre: Mounted lustre-client [ 8350.430856] LustreError: 309034:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211f9a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8350.450103] LustreError: 309034:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8350.488147] Lustre: Unmounted lustre-client [ 8352.943388] Key type lgssc unregistered [ 8353.174734] LNet: 309282:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8353.186587] LNetError: 309282:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8353.238311] LNet: Removed LNI 192.168.202.58@tcp [ 8353.958160] Key type .llcrypt unregistered [ 8353.960597] Key type ._llcrypt unregistered [ 8354.468950] Key type ._llcrypt registered [ 8354.470691] Key type .llcrypt registered [ 8355.001564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8355.023337] alg: No test for adler32 (adler32-zlib) [ 8356.106299] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8356.291246] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8357.959576] Key type lgssc registered [ 8359.141858] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8369.679157] Lustre: DEBUG MARKER: Iteration 15 [ 8370.153827] LustreError: 310102:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8370.170056] LustreError: 310104:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8370.183659] LustreError: 310102:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4972 [ 8371.562961] Lustre: Mounted lustre-client [ 8371.564553] Lustre: Skipped 1 previous similar message [ 8372.987599] LustreError: 310214:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0239d15000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8373.014542] LustreError: 310214:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8373.064849] Lustre: Unmounted lustre-client [ 8375.931880] Key type lgssc unregistered [ 8376.273067] LNet: 310458:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8376.277812] LNetError: 310458:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8376.292815] LNet: Removed LNI 192.168.202.58@tcp [ 8377.094180] Key type .llcrypt unregistered [ 8377.095913] Key type ._llcrypt unregistered [ 8378.073377] Key type ._llcrypt registered [ 8378.075028] Key type .llcrypt registered [ 8378.272740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8378.286493] alg: No test for adler32 (adler32-zlib) [ 8379.428959] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8379.732580] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8381.439532] Key type lgssc registered [ 8382.738532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8393.411060] Lustre: DEBUG MARKER: Iteration 16 [ 8393.744805] LustreError: 311279:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8393.747965] LustreError: 311278:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8393.756354] LustreError: 311279:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8394.966708] Lustre: Mounted lustre-client [ 8396.301915] LustreError: 311381:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02035d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8396.334932] LustreError: 311381:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8396.375714] Lustre: Unmounted lustre-client [ 8398.886454] Key type lgssc unregistered [ 8399.257292] LNet: 311623:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8399.265627] LNetError: 311623:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8399.279983] LNet: Removed LNI 192.168.202.58@tcp [ 8400.026261] Key type .llcrypt unregistered [ 8400.027569] Key type ._llcrypt unregistered [ 8400.789090] Key type ._llcrypt registered [ 8400.790617] Key type .llcrypt registered [ 8401.261735] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8401.276425] alg: No test for adler32 (adler32-zlib) [ 8402.362751] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8402.586671] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8404.319370] Key type lgssc registered [ 8405.872213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8416.659890] Lustre: DEBUG MARKER: Iteration 17 [ 8416.926885] LustreError: 312439:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8416.930179] LustreError: 312438:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8416.934944] LustreError: 312439:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8418.251411] Lustre: Mounted lustre-client [ 8419.752419] LustreError: 312552:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0207de1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8419.770221] LustreError: 312552:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8419.794549] LustreError: 312553:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8419.838724] Lustre: Unmounted lustre-client [ 8423.584354] Key type lgssc unregistered [ 8424.006445] LNet: 312794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8424.027421] LNetError: 312794:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8424.043254] LNet: Removed LNI 192.168.202.58@tcp [ 8425.065465] Key type .llcrypt unregistered [ 8425.067078] Key type ._llcrypt unregistered [ 8426.174336] Key type ._llcrypt registered [ 8426.175907] Key type .llcrypt registered [ 8427.090884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8427.105633] alg: No test for adler32 (adler32-zlib) [ 8428.363102] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8428.586474] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8430.304889] Key type lgssc registered [ 8432.051501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8442.760895] Lustre: DEBUG MARKER: Iteration 18 [ 8443.117488] LustreError: 313613:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8443.120269] LustreError: 313614:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8443.127262] LustreError: 313613:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8444.392412] Lustre: Mounted lustre-client [ 8445.631705] LustreError: 313722:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203127800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8445.660131] LustreError: 313724:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8445.672447] LustreError: 313724:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8445.705548] Lustre: Unmounted lustre-client [ 8448.372849] Key type lgssc unregistered [ 8448.639840] LNet: 313965:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8448.653575] LNetError: 313965:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8448.682734] LNet: Removed LNI 192.168.202.58@tcp [ 8449.267151] Key type .llcrypt unregistered [ 8449.271814] Key type ._llcrypt unregistered [ 8450.231205] Key type ._llcrypt registered [ 8450.237991] Key type .llcrypt registered [ 8450.493116] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8450.509682] alg: No test for adler32 (adler32-zlib) [ 8451.569774] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8451.794550] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8453.503162] Key type lgssc registered [ 8454.947074] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8465.921357] Lustre: DEBUG MARKER: Iteration 19 [ 8466.264625] LustreError: 314786:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8466.265431] LustreError: 314785:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8466.288796] LustreError: 314786:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 8468.534380] Lustre: Mounted lustre-client [ 8469.787773] LustreError: 314891:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f022050d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8469.818598] LustreError: 314891:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8469.851375] Lustre: Unmounted lustre-client [ 8472.356521] Key type lgssc unregistered [ 8472.582655] LNet: 315136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8472.588513] LNetError: 315136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8472.604098] LNet: Removed LNI 192.168.202.58@tcp [ 8473.161400] Key type .llcrypt unregistered [ 8473.168317] Key type ._llcrypt unregistered [ 8474.248155] Key type ._llcrypt registered [ 8474.254604] Key type .llcrypt registered [ 8474.412786] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8474.430864] alg: No test for adler32 (adler32-zlib) [ 8475.542454] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8475.743498] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8477.450695] Key type lgssc registered [ 8478.912525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8490.474162] Lustre: DEBUG MARKER: Iteration 20 [ 8490.807360] LustreError: 315946:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8490.854970] LustreError: 315972:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8490.862859] LustreError: 315946:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4952 [ 8492.116905] Lustre: Mounted lustre-client [ 8493.433785] LustreError: 316065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02070b6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8493.450173] LustreError: 316065:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8493.462390] LustreError: 316066:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8493.498086] Lustre: Unmounted lustre-client [ 8496.112054] Key type lgssc unregistered [ 8496.410271] LNet: 316307:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8496.422947] LNetError: 316307:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8496.453734] LNet: Removed LNI 192.168.202.58@tcp [ 8497.274219] Key type .llcrypt unregistered [ 8497.280590] Key type ._llcrypt unregistered [ 8497.947412] Key type ._llcrypt registered [ 8497.950367] Key type .llcrypt registered [ 8498.378506] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8498.387686] alg: No test for adler32 (adler32-zlib) [ 8499.435331] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8499.628516] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8501.295328] Key type lgssc registered [ 8502.432515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8511.615201] Lustre: DEBUG MARKER: Iteration 21 [ 8512.053334] LustreError: 317124:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8512.054091] LustreError: 317125:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8512.067781] LustreError: 317124:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8514.353542] Lustre: Mounted lustre-client [ 8514.359913] Lustre: Skipped 1 previous similar message [ 8515.669279] LustreError: 317224:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f023adb5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8515.698803] LustreError: 317224:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8515.750755] Lustre: Unmounted lustre-client [ 8518.805975] Key type lgssc unregistered [ 8519.128943] LNet: 317471:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8519.152687] LNetError: 317471:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8519.182767] LNet: Removed LNI 192.168.202.58@tcp [ 8519.841892] Key type .llcrypt unregistered [ 8519.843704] Key type ._llcrypt unregistered [ 8521.143573] Key type ._llcrypt registered [ 8521.161790] Key type .llcrypt registered [ 8521.985576] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8522.017657] alg: No test for adler32 (adler32-zlib) [ 8523.314300] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8523.588377] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8525.385900] Key type lgssc registered [ 8526.940635] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8539.695691] Lustre: DEBUG MARKER: Iteration 22 [ 8540.115256] LustreError: 318287:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8540.118112] LustreError: 318292:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8540.130801] LustreError: 318287:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8541.323151] Lustre: Mounted lustre-client [ 8542.563988] LustreError: 318400:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02035d7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8542.584420] LustreError: 318400:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8542.612904] Lustre: Unmounted lustre-client [ 8544.721170] Key type lgssc unregistered [ 8544.962696] LNet: 318642:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8544.979349] LNetError: 318642:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8545.001281] LNet: Removed LNI 192.168.202.58@tcp [ 8545.672171] Key type .llcrypt unregistered [ 8545.673713] Key type ._llcrypt unregistered [ 8546.250352] Key type ._llcrypt registered [ 8546.405953] Key type .llcrypt registered [ 8546.645890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8546.658472] alg: No test for adler32 (adler32-zlib) [ 8547.670402] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8547.866360] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8549.535207] Key type lgssc registered [ 8550.552347] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8560.584106] Lustre: DEBUG MARKER: Iteration 23 [ 8560.968214] LustreError: 319460:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8560.978884] LustreError: 319464:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8560.983444] LustreError: 319460:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8562.211235] Lustre: Mounted lustre-client [ 8563.445592] LustreError: 319569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203edf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8563.455134] LustreError: 319569:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8563.460070] LustreError: 319568:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8563.492303] Lustre: Unmounted lustre-client [ 8565.972267] Key type lgssc unregistered [ 8566.307354] LNet: 319810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8566.327865] LNetError: 319810:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8566.360445] LNet: Removed LNI 192.168.202.58@tcp [ 8566.952458] Key type .llcrypt unregistered [ 8566.953910] Key type ._llcrypt unregistered [ 8567.827554] Key type ._llcrypt registered [ 8567.828798] Key type .llcrypt registered [ 8567.973657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8567.985883] alg: No test for adler32 (adler32-zlib) [ 8569.037386] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8569.249381] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8570.887225] Key type lgssc registered [ 8572.118820] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8582.338557] Lustre: DEBUG MARKER: Iteration 24 [ 8582.691126] LustreError: 320628:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8582.691933] LustreError: 320629:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8582.700465] LustreError: 320628:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8583.959022] Lustre: Mounted lustre-client [ 8585.155854] LustreError: 320732:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f023ac17800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8585.174377] LustreError: 320732:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8585.201896] Lustre: Unmounted lustre-client [ 8587.518816] Key type lgssc unregistered [ 8587.785520] LNet: 320979:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8587.797341] LNetError: 320979:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8587.811960] LNet: Removed LNI 192.168.202.58@tcp [ 8588.360138] Key type .llcrypt unregistered [ 8588.362457] Key type ._llcrypt unregistered [ 8589.005070] Key type ._llcrypt registered [ 8589.016605] Key type .llcrypt registered [ 8589.394078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8589.410590] alg: No test for adler32 (adler32-zlib) [ 8590.489571] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8590.697792] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8592.399209] Key type lgssc registered [ 8593.618856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8603.699779] Lustre: DEBUG MARKER: Iteration 25 [ 8604.077199] LustreError: 321788:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8604.082412] LustreError: 321803:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8604.091722] LustreError: 321788:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8605.344779] Lustre: Mounted lustre-client [ 8606.833555] LustreError: 321902:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203120000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8606.867485] LustreError: 321902:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8606.922901] Lustre: Unmounted lustre-client [ 8609.678757] Key type lgssc unregistered [ 8610.028209] LNet: 322143:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8610.033666] LNetError: 322143:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8610.054434] LNet: Removed LNI 192.168.202.58@tcp [ 8610.672085] Key type .llcrypt unregistered [ 8610.673628] Key type ._llcrypt unregistered [ 8611.741769] Key type ._llcrypt registered [ 8611.799467] Key type .llcrypt registered [ 8612.219660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8612.229190] alg: No test for adler32 (adler32-zlib) [ 8613.340440] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8613.556825] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8615.223207] Key type lgssc registered [ 8616.401602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8626.333283] Lustre: DEBUG MARKER: Iteration 26 [ 8626.602132] LustreError: 322956:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8626.608902] LustreError: 322960:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8626.616172] LustreError: 322956:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8627.828970] Lustre: Mounted lustre-client [ 8629.564508] LustreError: 323070:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02217b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8629.591465] LustreError: 323070:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8629.621944] Lustre: Unmounted lustre-client [ 8632.409904] Key type lgssc unregistered [ 8632.802186] LNet: 323313:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8632.806110] LNetError: 323313:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8633.831505] LNet: Removed LNI 192.168.202.58@tcp [ 8634.561208] Key type .llcrypt unregistered [ 8634.565092] Key type ._llcrypt unregistered [ 8635.662396] Key type ._llcrypt registered [ 8635.664633] Key type .llcrypt registered [ 8635.886792] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8635.903174] alg: No test for adler32 (adler32-zlib) [ 8636.946957] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8637.140824] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8638.807898] Key type lgssc registered [ 8639.690721] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8648.762802] Lustre: DEBUG MARKER: Iteration 27 [ 8649.147199] LustreError: 324132:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8649.150757] LustreError: 324133:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8649.155078] LustreError: 324132:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8650.418271] Lustre: Mounted lustre-client [ 8651.780818] LustreError: 324234:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02274f3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8651.825447] LustreError: 324234:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8651.893452] Lustre: Unmounted lustre-client [ 8654.404053] Key type lgssc unregistered [ 8654.677553] LNet: 324483:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8654.686579] LNetError: 324483:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8654.698177] LNet: Removed LNI 192.168.202.58@tcp [ 8655.323444] Key type .llcrypt unregistered [ 8655.329145] Key type ._llcrypt unregistered [ 8656.046220] Key type ._llcrypt registered [ 8656.047883] Key type .llcrypt registered [ 8656.567547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8656.578427] alg: No test for adler32 (adler32-zlib) [ 8657.590494] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8657.802845] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8659.463366] Key type lgssc registered [ 8660.421640] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8670.452100] Lustre: DEBUG MARKER: Iteration 28 [ 8670.861508] LustreError: 325300:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8670.865125] LustreError: 325301:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8670.879892] LustreError: 325300:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 8672.101930] Lustre: Mounted lustre-client [ 8672.105329] Lustre: Skipped 1 previous similar message [ 8673.176613] LustreError: 325409:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f022059e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8673.197932] LustreError: 325409:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8673.201039] LustreError: 325409:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8673.228151] Lustre: Unmounted lustre-client [ 8673.232173] Lustre: Skipped 1 previous similar message [ 8675.114445] Key type lgssc unregistered [ 8675.354741] LNet: 325650:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8675.365906] LNetError: 325650:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8675.403552] LNet: Removed LNI 192.168.202.58@tcp [ 8675.942148] Key type .llcrypt unregistered [ 8675.943833] Key type ._llcrypt unregistered [ 8676.457486] Key type ._llcrypt registered [ 8676.458890] Key type .llcrypt registered [ 8676.840623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8676.857602] alg: No test for adler32 (adler32-zlib) [ 8677.884985] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8678.113713] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8679.799313] Key type lgssc registered [ 8680.726961] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8689.952572] Lustre: DEBUG MARKER: Iteration 29 [ 8690.320332] LustreError: 326471:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8690.320432] LustreError: 326472:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8690.341840] LustreError: 326471:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 8691.569062] Lustre: Mounted lustre-client [ 8692.539427] LustreError: 326577:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f020538e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8692.549733] LustreError: 326579:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8692.555887] LustreError: 326577:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8692.564437] LustreError: 326579:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8692.592227] Lustre: Unmounted lustre-client [ 8695.164770] Key type lgssc unregistered [ 8695.444176] LNet: 326818:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8695.449267] LNetError: 326818:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8695.466197] LNet: Removed LNI 192.168.202.58@tcp [ 8696.052674] Key type .llcrypt unregistered [ 8696.054271] Key type ._llcrypt unregistered [ 8697.014066] Key type ._llcrypt registered [ 8697.018443] Key type .llcrypt registered [ 8697.245780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8697.262166] alg: No test for adler32 (adler32-zlib) [ 8698.296218] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8698.550375] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8700.199180] Key type lgssc registered [ 8701.468521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8712.116915] Lustre: DEBUG MARKER: Iteration 30 [ 8712.483848] LustreError: 327638:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8712.484028] LustreError: 327634:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8712.500952] LustreError: 327638:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8713.791640] Lustre: Mounted lustre-client [ 8715.099391] LustreError: 327745:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f023d0d7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8715.112173] LustreError: 327745:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8715.147145] Lustre: Unmounted lustre-client [ 8717.769424] Key type lgssc unregistered [ 8718.017358] LNet: 327988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8718.023505] LNetError: 327988:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8718.035852] LNet: Removed LNI 192.168.202.58@tcp [ 8718.634164] Key type .llcrypt unregistered [ 8718.637259] Key type ._llcrypt unregistered [ 8719.355326] Key type ._llcrypt registered [ 8719.361313] Key type .llcrypt registered [ 8719.835934] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8719.858732] alg: No test for adler32 (adler32-zlib) [ 8720.954475] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8721.160891] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8722.831191] Key type lgssc registered [ 8724.102654] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8734.236922] Lustre: DEBUG MARKER: Iteration 31 [ 8734.530511] LustreError: 328806:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8734.534169] LustreError: 328807:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8734.542621] LustreError: 328806:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8735.725368] Lustre: Mounted lustre-client [ 8736.916874] LustreError: 328909:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02104c8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8736.950189] LustreError: 328909:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8736.999308] Lustre: Unmounted lustre-client [ 8739.570566] Key type lgssc unregistered [ 8739.814220] LNet: 329157:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8739.842427] LNetError: 329157:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8739.861752] LNet: Removed LNI 192.168.202.58@tcp [ 8740.465467] Key type .llcrypt unregistered [ 8740.467084] Key type ._llcrypt unregistered [ 8741.457154] Key type ._llcrypt registered [ 8741.458380] Key type .llcrypt registered [ 8741.801215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8741.808175] alg: No test for adler32 (adler32-zlib) [ 8742.931301] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8743.146954] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8744.873330] Key type lgssc registered [ 8746.072400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8757.032549] Lustre: DEBUG MARKER: Iteration 32 [ 8757.384278] LustreError: 329977:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8757.386026] LustreError: 329978:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8757.396088] LustreError: 329977:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8758.708116] Lustre: Mounted lustre-client [ 8759.988758] LustreError: 330085:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0207435800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8760.007823] LustreError: 330085:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8760.043264] Lustre: Unmounted lustre-client [ 8762.519223] Key type lgssc unregistered [ 8762.827296] LNet: 330330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8762.839609] LNetError: 330330:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8762.861811] LNet: Removed LNI 192.168.202.58@tcp [ 8763.524315] Key type .llcrypt unregistered [ 8763.527639] Key type ._llcrypt unregistered [ 8764.393968] Key type ._llcrypt registered [ 8764.395663] Key type .llcrypt registered [ 8764.981120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8764.996259] alg: No test for adler32 (adler32-zlib) [ 8766.084413] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8766.387034] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8768.059074] Key type lgssc registered [ 8769.319343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8779.142353] Lustre: DEBUG MARKER: Iteration 33 [ 8779.576681] LustreError: 331145:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8779.586933] LustreError: 331155:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8779.592059] LustreError: 331145:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8780.860222] Lustre: Mounted lustre-client [ 8782.205698] LustreError: 331247:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f020eca4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8782.222041] LustreError: 331247:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8782.271076] Lustre: Unmounted lustre-client [ 8784.875284] Key type lgssc unregistered [ 8785.139275] LNet: 331495:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8785.143291] LNetError: 331495:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8785.162649] LNet: Removed LNI 192.168.202.58@tcp [ 8785.838178] Key type .llcrypt unregistered [ 8785.840805] Key type ._llcrypt unregistered [ 8786.422067] Key type ._llcrypt registered [ 8786.423471] Key type .llcrypt registered [ 8786.790041] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8786.807139] alg: No test for adler32 (adler32-zlib) [ 8787.875826] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8788.146247] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8789.847693] Key type lgssc registered [ 8790.834860] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8800.256605] Lustre: DEBUG MARKER: Iteration 34 [ 8800.672570] LustreError: 332315:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8800.676065] LustreError: 332316:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8800.687169] LustreError: 332315:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8801.906440] Lustre: Mounted lustre-client [ 8803.260398] LustreError: 332418:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f020d918000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8803.277487] LustreError: 332418:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8803.315433] Lustre: Unmounted lustre-client [ 8805.875655] Key type lgssc unregistered [ 8806.148343] LNet: 332667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8806.156206] LNetError: 332667:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8807.210947] LNet: Removed LNI 192.168.202.58@tcp [ 8807.872145] Key type .llcrypt unregistered [ 8807.875461] Key type ._llcrypt unregistered [ 8808.639222] Key type ._llcrypt registered [ 8808.641708] Key type .llcrypt registered [ 8809.306467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8809.319653] alg: No test for adler32 (adler32-zlib) [ 8810.408681] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8810.623816] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8812.311215] Key type lgssc registered [ 8813.623574] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8825.167639] Lustre: DEBUG MARKER: Iteration 35 [ 8825.469392] LustreError: 333485:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8825.470522] LustreError: 333488:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8825.485239] LustreError: 333485:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8826.700364] Lustre: Mounted lustre-client [ 8828.048437] LustreError: 333595:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211d74000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8828.060094] LustreError: 333595:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8828.064559] LustreError: 333596:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8828.120364] Lustre: Unmounted lustre-client [ 8830.707839] Key type lgssc unregistered [ 8830.949386] LNet: 333838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8830.961893] LNetError: 333838:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8830.974532] LNet: Removed LNI 192.168.202.58@tcp [ 8831.617176] Key type .llcrypt unregistered [ 8831.619614] Key type ._llcrypt unregistered [ 8832.194426] Key type ._llcrypt registered [ 8832.195877] Key type .llcrypt registered [ 8832.634698] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8832.645453] alg: No test for adler32 (adler32-zlib) [ 8833.745196] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8834.075284] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8835.773210] Key type lgssc registered [ 8837.281975] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8849.839791] Lustre: DEBUG MARKER: Iteration 36 [ 8850.256020] LustreError: 334657:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8850.263618] LustreError: 334658:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8850.279126] LustreError: 334657:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8851.638217] Lustre: Mounted lustre-client [ 8852.839846] LustreError: 334761:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02353e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8852.856590] LustreError: 334761:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8852.898130] Lustre: Unmounted lustre-client [ 8855.141816] Key type lgssc unregistered [ 8855.395778] LNet: 335005:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8855.405097] LNetError: 335005:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8855.422435] LNet: Removed LNI 192.168.202.58@tcp [ 8855.927422] Key type .llcrypt unregistered [ 8855.929054] Key type ._llcrypt unregistered [ 8856.535104] Key type ._llcrypt registered [ 8856.536857] Key type .llcrypt registered [ 8856.962242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8856.977943] alg: No test for adler32 (adler32-zlib) [ 8858.059187] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8858.269768] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8859.967245] Key type lgssc registered [ 8861.054916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8870.284913] Lustre: DEBUG MARKER: Iteration 37 [ 8870.532266] LustreError: 335822:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8870.534130] LustreError: 335825:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8870.555817] LustreError: 335822:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8871.864485] Lustre: Mounted lustre-client [ 8873.572367] LustreError: 335924:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211d74000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8873.601377] LustreError: 335924:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8873.666953] Lustre: Unmounted lustre-client [ 8876.426834] Key type lgssc unregistered [ 8876.824993] LNet: 336173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8876.840729] LNetError: 336173:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8876.878316] LNet: Removed LNI 192.168.202.58@tcp [ 8877.754246] Key type .llcrypt unregistered [ 8877.759966] Key type ._llcrypt unregistered [ 8878.792773] Key type ._llcrypt registered [ 8878.794190] Key type .llcrypt registered [ 8879.290898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8879.316074] alg: No test for adler32 (adler32-zlib) [ 8880.441077] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8880.607169] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8882.271192] Key type lgssc registered [ 8883.809740] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8893.767951] Lustre: DEBUG MARKER: Iteration 38 [ 8894.147558] LustreError: 336990:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8894.151772] LustreError: 336991:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8894.161095] LustreError: 336990:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 8895.371540] Lustre: Mounted lustre-client [ 8896.607552] LustreError: 337089:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02070b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8896.641926] LustreError: 337089:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8896.685089] Lustre: Unmounted lustre-client [ 8899.578104] Key type lgssc unregistered [ 8899.830358] LNet: 337336:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8899.840301] LNetError: 337336:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8899.881338] LNet: Removed LNI 192.168.202.58@tcp [ 8900.477175] Key type .llcrypt unregistered [ 8900.479514] Key type ._llcrypt unregistered [ 8901.130761] Key type ._llcrypt registered [ 8901.132318] Key type .llcrypt registered [ 8901.496819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8901.507253] alg: No test for adler32 (adler32-zlib) [ 8902.500874] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8902.680686] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8904.367176] Key type lgssc registered [ 8905.735185] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8915.082892] Lustre: DEBUG MARKER: Iteration 39 [ 8915.354696] LustreError: 338156:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8915.357741] LustreError: 338155:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8915.365804] LustreError: 338156:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8916.572619] Lustre: Mounted lustre-client [ 8917.617892] LustreError: 338265:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0203126800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8917.637136] LustreError: 338266:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8917.640455] LustreError: 338266:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8917.671920] Lustre: Unmounted lustre-client [ 8920.102818] Key type lgssc unregistered [ 8920.414144] LNet: 338506:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8920.423936] LNetError: 338506:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8920.444406] LNet: Removed LNI 192.168.202.58@tcp [ 8921.016176] Key type .llcrypt unregistered [ 8921.017848] Key type ._llcrypt unregistered [ 8922.333184] Key type ._llcrypt registered [ 8922.334970] Key type .llcrypt registered [ 8922.725525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8922.753033] alg: No test for adler32 (adler32-zlib) [ 8923.905495] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8924.210752] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8925.927339] Key type lgssc registered [ 8927.416563] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8936.936263] Lustre: DEBUG MARKER: Iteration 40 [ 8937.331675] LustreError: 339325:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8937.337166] LustreError: 339327:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8937.341364] LustreError: 339325:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8938.589992] Lustre: Mounted lustre-client [ 8939.700283] LustreError: 339434:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02217b3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8939.718087] LustreError: 339434:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8939.762968] Lustre: Unmounted lustre-client [ 8942.288474] Key type lgssc unregistered [ 8942.641977] LNet: 339675:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8942.647722] LNetError: 339675:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8943.657688] LNet: Removed LNI 192.168.202.58@tcp [ 8944.278327] Key type .llcrypt unregistered [ 8944.281359] Key type ._llcrypt unregistered [ 8945.294576] Key type ._llcrypt registered [ 8945.296022] Key type .llcrypt registered [ 8945.655586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8945.665646] alg: No test for adler32 (adler32-zlib) [ 8946.747151] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8946.943675] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8948.599400] Key type lgssc registered [ 8949.872261] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8959.785230] Lustre: DEBUG MARKER: Iteration 41 [ 8959.972958] LustreError: 340494:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8959.973832] LustreError: 340496:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8959.981083] LustreError: 340494:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8961.186491] Lustre: Mounted lustre-client [ 8962.546784] LustreError: 340600:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0207437000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8962.564995] LustreError: 340600:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8962.619217] Lustre: Unmounted lustre-client [ 8965.036485] Key type lgssc unregistered [ 8965.249971] LNet: 340844:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8965.257992] LNetError: 340844:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8965.276128] LNet: Removed LNI 192.168.202.58@tcp [ 8965.918174] Key type .llcrypt unregistered [ 8965.919700] Key type ._llcrypt unregistered [ 8966.578635] Key type ._llcrypt registered [ 8966.582186] Key type .llcrypt registered [ 8966.932198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8966.946191] alg: No test for adler32 (adler32-zlib) [ 8968.004566] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8968.195486] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8969.847187] Key type lgssc registered [ 8970.726674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8979.124268] Lustre: DEBUG MARKER: Iteration 42 [ 8979.434406] LustreError: 341661:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8979.435509] LustreError: 341664:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8979.446145] LustreError: 341661:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8980.757338] Lustre: Mounted lustre-client [ 8981.978993] LustreError: 341768:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02353ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8982.011503] LustreError: 341768:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8982.042113] Lustre: Unmounted lustre-client [ 8984.390856] Key type lgssc unregistered [ 8984.691386] LNet: 342015:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8984.705508] LNetError: 342015:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8984.731649] LNet: Removed LNI 192.168.202.58@tcp [ 8985.257203] Key type .llcrypt unregistered [ 8985.258601] Key type ._llcrypt unregistered [ 8986.070715] Key type ._llcrypt registered [ 8986.082066] Key type .llcrypt registered [ 8986.471811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8986.487677] alg: No test for adler32 (adler32-zlib) [ 8987.583938] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 8987.832579] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 8989.503219] Key type lgssc registered [ 8990.797436] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9001.411713] Lustre: DEBUG MARKER: Iteration 43 [ 9001.674268] LustreError: 342829:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9001.693792] LustreError: 342844:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9001.702905] LustreError: 342829:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [ 9002.948060] Lustre: Mounted lustre-client [ 9004.132965] LustreError: 342941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211f99800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9004.148077] LustreError: 342941:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9004.189343] Lustre: Unmounted lustre-client [ 9006.633519] Key type lgssc unregistered [ 9006.907942] LNet: 343183:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9006.915562] LNetError: 343183:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9006.931255] LNet: Removed LNI 192.168.202.58@tcp [ 9007.500166] Key type .llcrypt unregistered [ 9007.507391] Key type ._llcrypt unregistered [ 9008.021389] Key type ._llcrypt registered [ 9008.023215] Key type .llcrypt registered [ 9008.572984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9008.600808] alg: No test for adler32 (adler32-zlib) [ 9009.705681] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9009.953357] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9011.679271] Key type lgssc registered [ 9012.937394] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9023.633061] Lustre: DEBUG MARKER: Iteration 44 [ 9023.987325] LustreError: 343999:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9023.993563] LustreError: 344002:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9023.998631] LustreError: 343999:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9025.270409] Lustre: Mounted lustre-client [ 9026.307811] LustreError: 344111:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f021052a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9026.341781] LustreError: 344112:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9026.375205] Lustre: Unmounted lustre-client [ 9028.668279] Key type lgssc unregistered [ 9028.975948] LNet: 344352:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9028.983205] LNetError: 344352:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9028.997488] LNet: Removed LNI 192.168.202.58@tcp [ 9029.651194] Key type .llcrypt unregistered [ 9029.653123] Key type ._llcrypt unregistered [ 9030.448334] Key type ._llcrypt registered [ 9030.452124] Key type .llcrypt registered [ 9030.847470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9030.865816] alg: No test for adler32 (adler32-zlib) [ 9031.891315] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9032.120475] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9033.831171] Key type lgssc registered [ 9035.204791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9045.763373] Lustre: DEBUG MARKER: Iteration 45 [ 9046.078950] LustreError: 345166:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9046.082295] LustreError: 345167:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9046.087344] LustreError: 345166:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9047.307199] Lustre: Mounted lustre-client [ 9047.312809] Lustre: Skipped 1 previous similar message [ 9048.364265] LustreError: 345272:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0205397800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9048.377430] LustreError: 345272:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9048.420591] Lustre: Unmounted lustre-client [ 9051.019749] Key type lgssc unregistered [ 9051.260717] LNet: 345520:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9051.272746] LNetError: 345520:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9051.295872] LNet: Removed LNI 192.168.202.58@tcp [ 9051.938182] Key type .llcrypt unregistered [ 9051.947934] Key type ._llcrypt unregistered [ 9052.546791] Key type ._llcrypt registered [ 9052.551566] Key type .llcrypt registered [ 9053.085633] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9053.108759] alg: No test for adler32 (adler32-zlib) [ 9054.276300] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9054.532417] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9056.223283] Key type lgssc registered [ 9057.378540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9067.803186] Lustre: DEBUG MARKER: Iteration 46 [ 9068.176396] LustreError: 346337:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9068.180889] LustreError: 346340:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9068.190816] LustreError: 346337:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9069.418059] Lustre: Mounted lustre-client [ 9070.572632] LustreError: 346444:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0211f9d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9070.595185] LustreError: 346447:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9070.598192] LustreError: 346447:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9070.639741] Lustre: Unmounted lustre-client [ 9070.646769] Lustre: Skipped 1 previous similar message [ 9073.175296] Key type lgssc unregistered [ 9073.408985] LNet: 346686:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9073.419737] LNetError: 346686:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9073.440237] LNet: Removed LNI 192.168.202.58@tcp [ 9074.012212] Key type .llcrypt unregistered [ 9074.015393] Key type ._llcrypt unregistered [ 9074.878935] Key type ._llcrypt registered [ 9074.885746] Key type .llcrypt registered [ 9075.211258] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9075.227477] alg: No test for adler32 (adler32-zlib) [ 9076.280138] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9076.566451] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9078.231403] Key type lgssc registered [ 9079.316861] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9089.331399] Lustre: DEBUG MARKER: Iteration 47 [ 9089.732612] LustreError: 347505:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9089.733453] LustreError: 347506:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9089.753450] LustreError: 347505:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9091.009846] Lustre: Mounted lustre-client [ 9092.377050] LustreError: 347613:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02035d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9092.404356] LustreError: 347613:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9092.469785] Lustre: Unmounted lustre-client [ 9094.789739] Key type lgssc unregistered [ 9094.982145] LNet: 347856:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9094.989190] LNetError: 347856:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9095.006206] LNet: Removed LNI 192.168.202.58@tcp [ 9095.567187] Key type .llcrypt unregistered [ 9095.569086] Key type ._llcrypt unregistered [ 9096.183719] Key type ._llcrypt registered [ 9096.187364] Key type .llcrypt registered [ 9096.588961] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9096.610031] alg: No test for adler32 (adler32-zlib) [ 9097.671664] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9097.946418] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9099.664166] Key type lgssc registered [ 9100.964771] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9110.388848] Lustre: DEBUG MARKER: Iteration 48 [ 9110.752215] LustreError: 348672:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9110.752572] LustreError: 348674:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9110.771738] LustreError: 348672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 9112.978363] Lustre: Mounted lustre-client [ 9114.050345] LustreError: 348783:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0205395800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9114.065979] LustreError: 348783:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9114.091108] Lustre: Unmounted lustre-client [ 9115.796272] Key type lgssc unregistered [ 9115.972580] LNet: 349024:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9115.976952] LNetError: 349024:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9115.988545] LNet: Removed LNI 192.168.202.58@tcp [ 9116.497327] Key type .llcrypt unregistered [ 9116.499471] Key type ._llcrypt unregistered [ 9116.966809] Key type ._llcrypt registered [ 9116.970343] Key type .llcrypt registered [ 9117.289431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9117.300266] alg: No test for adler32 (adler32-zlib) [ 9118.340384] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9118.539315] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9120.199280] Key type lgssc registered [ 9121.345407] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9129.934501] Lustre: DEBUG MARKER: Iteration 49 [ 9130.146581] LustreError: 349842:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9130.148574] LustreError: 349843:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9130.156254] LustreError: 349842:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9131.314315] Lustre: Mounted lustre-client [ 9132.148906] LustreError: 349949:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f0218070000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9132.160951] LustreError: 349949:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9132.164537] LustreError: 349949:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9132.188424] Lustre: Unmounted lustre-client [ 9134.195042] Key type lgssc unregistered [ 9134.410307] LNet: 350193:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9134.415469] LNetError: 350193:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9134.431981] LNet: Removed LNI 192.168.202.58@tcp [ 9134.952223] Key type .llcrypt unregistered [ 9134.954667] Key type ._llcrypt unregistered [ 9135.601281] Key type ._llcrypt registered [ 9135.605240] Key type .llcrypt registered [ 9135.902786] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9135.919641] alg: No test for adler32 (adler32-zlib) [ 9136.999372] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9137.200407] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9138.912462] Key type lgssc registered [ 9140.313550] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9149.521838] Lustre: DEBUG MARKER: Iteration 50 [ 9149.826195] LustreError: 351012:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9149.826936] LustreError: 351011:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9149.833289] LustreError: 351012:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9151.037819] Lustre: Mounted lustre-client [ 9152.242104] LustreError: 351112:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02035d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9152.264311] LustreError: 351112:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9152.310419] Lustre: Unmounted lustre-client [ 9154.713539] Key type lgssc unregistered [ 9154.973174] LNet: 351361:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9154.982732] LNetError: 351361:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9156.007495] LNet: Removed LNI 192.168.202.58@tcp [ 9156.614213] Key type .llcrypt unregistered [ 9156.616150] Key type ._llcrypt unregistered [ 9157.280357] Key type ._llcrypt registered [ 9157.281755] Key type .llcrypt registered [ 9157.862613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9157.892948] alg: No test for adler32 (adler32-zlib) [ 9159.011395] Lustre: Lustre: Build Version: 2.16.61_43_ga83672f [ 9159.371411] LNet: Added LNI 192.168.202.58@tcp [8/256/0/180] [ 9161.103237] Key type lgssc registered [ 9162.264499] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9172.297724] Lustre: Mounted lustre-client [ 9179.012515] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:00:26 (1763341226) [ 9195.487206] Lustre: 352694:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341228/real 1763341228] req@ffff9f023a49d500 x1848997269481088/t0(0) o36->lustre-MDT0000-mdc-ffff9f023d694000@192.168.202.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763341244 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9195.501806] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection to lustre-MDT0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9195.533216] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9211.871260] Lustre: 352694:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341244/real 1763341244] req@ffff9f023a49d500 x1848997269481088/t0(0) o36->lustre-MDT0000-mdc-ffff9f023d694000@192.168.202.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763341260 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9211.898369] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection to lustre-MDT0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9211.941932] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9228.255151] Lustre: 352694:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341260/real 1763341260] req@ffff9f023a49d500 x1848997269481088/t0(0) o36->lustre-MDT0000-mdc-ffff9f023d694000@192.168.202.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763341276 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9228.269485] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection to lustre-MDT0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9228.293072] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9243.615415] Lustre: 352694:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341276/real 1763341276] req@ffff9f023a49d500 x1848997269481088/t0(0) o36->lustre-MDT0000-mdc-ffff9f023d694000@192.168.202.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763341292 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9243.632564] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection to lustre-MDT0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9243.651176] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9259.999192] Lustre: 352694:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341292/real 1763341292] req@ffff9f023a49d500 x1848997269481088/t0(0) o36->lustre-MDT0000-mdc-ffff9f023d694000@192.168.202.158@tcp:12/10 lens 496/440 e 0 to 1 dl 1763341308 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9260.042332] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection to lustre-MDT0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9260.078530] Lustre: lustre-MDT0000-mdc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9261.564839] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:01:48 (1763341308) [ 9263.060179] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 9264.878262] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:01:52 (1763341312) [ 9266.357520] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 9268.252808] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:01:55 (1763341315) [ 9274.678904] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:02:01 (1763341321) [ 9276.377754] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 9277.839082] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:02:05 (1763341325) [ 9279.159170] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 9280.688555] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:02:08 (1763341328) [ 9281.983506] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 9283.683766] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:02:11 (1763341331) [ 9304.863244] Lustre: 351553:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341337/real 1763341337] req@ffff9f023a49d180 x1848997269511680/t0(0) o4->lustre-OST0000-osc-ffff9f023d694000@192.168.202.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763341353 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9304.889978] Lustre: lustre-OST0000-osc-ffff9f023d694000: Connection to lustre-OST0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9304.932286] Lustre: lustre-OST0000-osc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9320.911321] Lustre: 351554:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763341353/real 1763341353] req@ffff9f023a49f100 x1848997269512320/t0(0) o4->lustre-OST0000-osc-ffff9f023d694000@192.168.202.158@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763341369 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9320.928050] Lustre: 351554:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 9320.935675] Lustre: lustre-OST0000-osc-ffff9f023d694000: Connection to lustre-OST0000 (at 192.168.202.158@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9320.953414] Lustre: lustre-OST0000-osc-ffff9f023d694000: Connection restored to 192.168.202.158@tcp (at 192.168.202.158@tcp) [ 9358.721680] Lustre: DEBUG MARKER: oleg258-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9f02035d5000.ost_server_uuid 50 [ 9360.278173] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9f02035d5000.ost_server_uuid in IDLE state after 0 sec [ 9362.196526] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9364.172770] Lustre: DEBUG MARKER: == sanityn test complete, duration 9154 sec ============== 20:03:31 (1763341411) [ 9366.291127] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:03:33 (1763341413) === [ 9594.424230] LustreError: 356810:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f02035d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9594.432888] LustreError: 356810:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9594.450138] Lustre: Unmounted lustre-client [ 9597.304635] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:07:24 (1763341644) === [ 9598.559734] LustreError: 357112:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9f023d694000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9598.565517] LustreError: 357112:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9598.579747] LustreError: 357112:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9598.582894] LustreError: 357112:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 9598.623087] Lustre: Unmounted lustre-client [ 9613.813407] Key type lgssc unregistered [ 9614.041141] LNet: 357592:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9614.074110] LNetError: 357592:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9614.095340] LNet: Removed LNI 192.168.202.58@tcp [ 9614.780759] Key type .llcrypt unregistered [ 9614.784144] Key type ._llcrypt unregistered