[ 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 456548940 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.002375] x2apic enabled [ 0.004005] Switched APIC routing to physical x2apic. [ 0.005017] kvm-guest: setup PV IPIs [ 0.007642] ..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.009015] pid_max: default: 32768 minimum: 301 [ 0.010128] LSM: Security Framework initializing [ 0.011052] Yama: becoming mindful. [ 0.012039] SELinux: Initializing. [ 0.013075] *** VALIDATE selinux *** [ 0.021546] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026399] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027153] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028117] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030002] *** VALIDATE tmpfs *** [ 0.031479] *** VALIDATE proc *** [ 0.032190] *** VALIDATE cgroup *** [ 0.033010] *** VALIDATE cgroup2 *** [ 0.034257] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035165] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037034] Spectre V2 : User space: Vulnerable [ 0.038012] Speculative Store Bypass: Vulnerable [ 0.041350] debug: unmapping init [mem 0xffffffffa5c59000-0xffffffffa5c60fff] [ 0.043173] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044700] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045024] ... version: 2 [ 0.046013] ... bit width: 48 [ 0.047010] ... generic registers: 4 [ 0.048016] ... value mask: 0000ffffffffffff [ 0.049016] ... max period: 00007fffffffffff [ 0.050012] ... fixed-purpose events: 3 [ 0.051011] ... event mask: 000000070000000f [ 0.052302] rcu: Hierarchical SRCU implementation. [ 0.054488] smp: Bringing up secondary CPUs ... [ 0.055508] x86: Booting SMP configuration: [ 0.056025] .... node #0, CPUs: #1 #2 #3 [ 0.060255] smp: Brought up 1 node, 4 CPUs [ 0.062013] smpboot: Max logical packages: 1 [ 0.063030] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.226239] node 0 deferred pages initialised in 162ms [ 0.230357] devtmpfs: initialized [ 0.232345] x86/mm: Memory block size: 128MB [ 0.235966] gcov: version magic: 0x41383552 [ 0.239379] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.243175] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.245458] pinctrl core: initialized pinctrl subsystem [ 0.248233] [ 0.248738] ************************************************************* [ 0.251016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.253019] ** ** [ 0.256019] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.258015] ** ** [ 0.261019] ** This means that this kernel is built to expose internal ** [ 0.263015] ** IOMMU data structures, which may compromise security on ** [ 0.265019] ** your system. ** [ 0.268019] ** ** [ 0.270016] ** If you see this message and you are not debugging the ** [ 0.272030] ** kernel, report this immediately to your vendor! ** [ 0.274015] ** ** [ 0.276020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.278019] ************************************************************* [ 0.281821] NET: Registered protocol family 16 [ 0.283518] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.286079] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.289082] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.294084] cpuidle: using governor menu [ 0.295692] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.296470] PCI: Using configuration type 1 for base access [ 0.297123] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.303129] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.305066] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.308162] cryptd: max_cpu_qlen set to 1000 [ 0.311236] ACPI: Added _OSI(Module Device) [ 0.313021] ACPI: Added _OSI(Processor Device) [ 0.314013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.316015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.319000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.326565] ACPI: Interpreter enabled [ 0.328077] ACPI: PM: (supports S0 S3 S4 S5) [ 0.329013] ACPI: Using IOAPIC for interrupt routing [ 0.331118] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.334387] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.344011] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.346057] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.349036] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.353198] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.359308] acpiphp: Slot [2] registered [ 0.360122] acpiphp: Slot [3] registered [ 0.362113] acpiphp: Slot [4] registered [ 0.363123] acpiphp: Slot [5] registered [ 0.365146] acpiphp: Slot [6] registered [ 0.367126] acpiphp: Slot [7] registered [ 0.368119] acpiphp: Slot [8] registered [ 0.370108] acpiphp: Slot [9] registered [ 0.371113] acpiphp: Slot [10] registered [ 0.373141] acpiphp: Slot [11] registered [ 0.375108] acpiphp: Slot [12] registered [ 0.377099] acpiphp: Slot [13] registered [ 0.378094] acpiphp: Slot [14] registered [ 0.380113] acpiphp: Slot [15] registered [ 0.381110] acpiphp: Slot [16] registered [ 0.383135] acpiphp: Slot [17] registered [ 0.385126] acpiphp: Slot [18] registered [ 0.386213] acpiphp: Slot [19] registered [ 0.388208] acpiphp: Slot [20] registered [ 0.390164] acpiphp: Slot [21] registered [ 0.391149] acpiphp: Slot [22] registered [ 0.393163] acpiphp: Slot [23] registered [ 0.394131] acpiphp: Slot [24] registered [ 0.396151] acpiphp: Slot [25] registered [ 0.398188] acpiphp: Slot [26] registered [ 0.399178] acpiphp: Slot [27] registered [ 0.401139] acpiphp: Slot [28] registered [ 0.402120] acpiphp: Slot [29] registered [ 0.404127] acpiphp: Slot [30] registered [ 0.406137] acpiphp: Slot [31] registered [ 0.407106] PCI host bridge to bus 0000:00 [ 0.409035] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.411038] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.414036] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.417053] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.419049] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.423037] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.425180] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.429269] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.434671] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.442016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.447013] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.450029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.452015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.454025] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.457348] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.460800] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.463060] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.465723] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.470022] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.482026] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.487035] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.493997] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.499015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.504017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.514021] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.523046] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.529022] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.535023] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.546022] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.557459] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.561453] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.564416] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.566390] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.569244] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.573265] iommu: Default domain type: Passthrough [ 0.575549] SCSI subsystem initialized [ 0.577235] ACPI: bus type USB registered [ 0.579139] usbcore: registered new interface driver usbfs [ 0.581131] usbcore: registered new interface driver hub [ 0.582000] usbcore: registered new device driver usb [ 0.583234] pps_core: LinuxPPS API ver. 1 registered [ 0.585019] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.588097] PTP clock support registered [ 0.593121] EDAC MC: Ver: 3.0.0 [ 0.595165] PCI: Using ACPI for IRQ routing [ 0.596871] NetLabel: Initializing [ 0.599018] NetLabel: domain hash size = 128 [ 0.601017] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.603136] NetLabel: unlabeled traffic allowed by default [ 0.606151] vgaarb: loaded [ 0.610297] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.612017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.617000] clocksource: Switched to clocksource kvm-clock [ 0.726698] VFS: Disk quotas dquot_6.6.0 [ 0.728759] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.731662] *** VALIDATE ramfs *** [ 0.732990] *** VALIDATE hugetlbfs *** [ 0.734466] pnp: PnP ACPI init [ 0.737527] pnp: PnP ACPI: found 6 devices [ 0.759294] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.762399] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.764420] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.766449] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.768794] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.771116] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.773900] NET: Registered protocol family 2 [ 0.776387] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.781777] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.785101] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.790249] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.793755] TCP: Hash tables configured (established 65536 bind 65536) [ 0.796780] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.799752] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.802860] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.806185] NET: Registered protocol family 1 [ 0.810636] RPC: Registered named UNIX socket transport module. [ 0.812838] RPC: Registered udp transport module. [ 0.814440] RPC: Registered tcp transport module. [ 0.816243] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.818945] NET: Registered protocol family 44 [ 0.820719] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.822810] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.824838] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.827064] PCI: CLS 0 bytes, default 64 [ 0.828879] Unpacking initramfs... [ 2.230898] debug: unmapping init [mem 0xffff8e42fcc64000-0xffff8e42fffcffff] [ 2.235110] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.237479] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.240495] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.770208] Initialise system trusted keyrings [ 2.773048] Key type blacklist registered [ 2.774814] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.784392] zbud: loaded [ 2.787810] *** VALIDATE nfs *** [ 2.789378] *** VALIDATE nfs4 *** [ 2.790830] pstore: using deflate compression [ 2.793642] Platform Keyring initialized [ 2.901739] NET: Registered protocol family 38 [ 2.903646] Key type asymmetric registered [ 2.905282] Asymmetric key parser 'x509' registered [ 2.907298] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.910540] io scheduler mq-deadline registered [ 2.912026] io scheduler kyber registered [ 2.913678] io scheduler bfq registered [ 2.916114] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.919355] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.922684] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.925736] ACPI: Power Button [PWRF] [ 3.015043] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.107066] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.205206] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.237983] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.268431] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.273630] Non-volatile memory driver v1.3 [ 3.275576] Linux agpgart interface v0.103 [ 3.309510] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.311965] vda: detected capacity change from 0 to 68526080 [ 3.325727] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.328625] vdb: detected capacity change from 0 to 1073741824 [ 3.334788] libphy: Fixed MDIO Bus: probed [ 3.340436] usbcore: registered new interface driver usbserial_generic [ 3.342331] usbserial: USB Serial support registered for generic [ 3.344092] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.347597] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.349450] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.351689] mousedev: PS/2 mouse device common for all mice [ 3.354613] rtc_cmos 00:05: RTC can wake from S4 [ 3.357365] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.358469] rtc_cmos 00:05: registered as rtc0 [ 3.363273] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.364639] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.367108] intel_pstate: CPU model not supported [ 3.373723] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.376103] hid: raw HID events driver (C) Jiri Kosina [ 3.380153] usbcore: registered new interface driver usbhid [ 3.382180] usbhid: USB HID core driver [ 3.383634] drop_monitor: Initializing network drop monitor service [ 3.386060] Initializing XFRM netlink socket [ 3.388283] NET: Registered protocol family 10 [ 3.391326] Segment Routing with IPv6 [ 3.392874] NET: Registered protocol family 17 [ 3.395053] mpls_gso: MPLS GSO support [ 3.400818] RAS: Correctable Errors collector initialized. [ 3.403165] AVX version of gcm_enc/dec engaged. [ 3.404979] AES CTR mode by8 optimization enabled [ 3.481667] sched_clock: Marking stable (3481645121, 0)->(4371635166, -889990045) [ 3.485098] registered taskstats version 1 [ 3.487115] Loading compiled-in X.509 certificates [ 3.489445] zswap: loaded using pool lzo/zbud [ 3.517410] Key type big_key registered [ 3.529938] Key type encrypted registered [ 3.531476] ima: No TPM chip found, activating TPM-bypass! [ 3.533363] ima: Allocated hash algorithm: sha1 [ 3.535053] ima: No architecture policies found [ 3.536644] evm: Initialising EVM extended attributes: [ 3.538309] evm: security.selinux [ 3.539511] evm: security.ima [ 3.540593] evm: security.capability [ 3.541928] evm: HMAC attrs: 0x1 [ 3.543959] rtc_cmos 00:05: setting system clock to 2025-11-17 00:51:44 UTC (1763340704) [ 3.549955] debug: unmapping init [mem 0xffffffffa6c03000-0xffffffffa6dfffff] [ 3.552812] debug: unmapping init [mem 0xffffffffa5982000-0xffffffffa5c58fff] [ 3.561117] Write protecting the kernel read-only data: 28672k [ 3.564492] debug: unmapping init [mem 0xffffffffa4003000-0xffffffffa41fffff] [ 3.567249] debug: unmapping init [mem 0xffffffffa4914000-0xffffffffa49fffff] [ 3.599564] 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.608746] systemd[1]: Detected virtualization kvm. [ 3.610566] systemd[1]: Detected architecture x86-64. [ 3.612473] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.641116] systemd[1]: No hostname configured. [ 3.642724] systemd[1]: Set hostname to . [ 3.645050] random: systemd: uninitialized urandom read (16 bytes read) [ 3.647706] systemd[1]: Initializing machine ID from random generator. [ 3.689782] random: ln: uninitialized urandom read (6 bytes read) [ 3.773298] random: systemd: uninitialized urandom read (16 bytes read) [ 3.776154] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.781141] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.786391] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Reached target Sockets. Starting Setup Virtual Console... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.458138] device-mapper: uevent: version 1.0.3 [ 4.460577] 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. [ 5.284264] virtio_net virtio0 ens2: renamed from eth0 [ 5.411313] scsi host0: ata_piix [ 5.430214] scsi host1: ata_piix [ 5.431752] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.434482] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.108211] dracut-initqueue[580]: RTNETLINK answers: File exists [ 10.047469] random: crng init done [ 10.048959] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.540543] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ 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 Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.667941] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.935786] SELinux: Disabled at runtime. [ 12.002639] 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) [ 12.010628] systemd[1]: Detected virtualization kvm. [ 12.012557] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.480815] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.488569] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.496639] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.500951] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.505629] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.513655] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.525480] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-sshd\x2dkeygen.slice. [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 rpc_pipefs.target. Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd File Systems. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ 12.670815] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ 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 ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.952674] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.210869] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.231232] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.316131] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.332490] EDAC sbridge: Ver: 1.1.2 [ 14.367247] Key type dns_resolver registered [ 14.675963] NFS: Registering the id_resolver key type [ 14.677368] Key type id_resolver registered [ 14.678253] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target sshd-keygen.target. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg438-client login: [ 71.859276] libcfs: loading out-of-tree module taints kernel. [ 72.109870] Key type ._llcrypt registered [ 72.111363] Key type .llcrypt registered [ 72.507515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 72.519313] alg: No test for adler32 (adler32-zlib) [ 74.026402] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 75.238544] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 76.991183] Key type lgssc registered [ 78.491683] Lustre: Echo OBD driver; http://www.lustre.org/ [ 81.772756] hrtimer: interrupt took 5175152 ns [ 192.279934] Lustre: Mounted lustre-client [ 197.028906] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 218.085865] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 24s idle [ 219.641334] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing check_logdir /tmp/testlogs/ [ 223.693663] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing yml_node [ 227.433581] Lustre: DEBUG MARKER: Client: 2.16.61.48 [ 229.139162] Lustre: DEBUG MARKER: MDS: 2.16.61.48 [ 230.711197] Lustre: DEBUG MARKER: OSS: 2.16.61.48 [ 231.929828] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 19:55:31 EST 2025 [ 249.000575] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 250.648657] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 252.149485] Lustre: DEBUG MARKER: === sanityn: start setup 19:55:51 (1763340951) === [ 252.918730] Lustre: Mounted lustre-client [ 256.784695] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing check_config_client /mnt/lustre [ 270.636806] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 273.375273] Lustre: lustre-OST0000-osc-ffff8e4348744000: disconnect after 21s idle [ 273.383624] Lustre: Skipped 1 previous similar message [ 280.763330] Lustre: DEBUG MARKER: === sanityn: finish setup 19:56:20 (1763340980) === [ 282.882214] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 19:56:22 (1763340982) [ 289.610763] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 19:56:29 (1763340989) [ 296.414704] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 19:56:35 (1763340995) [ 302.954314] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 19:56:42 (1763341002) [ 310.048796] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 19:56:49 (1763341009) [ 316.673304] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 19:56:56 (1763341016) [ 322.050513] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 19:57:01 (1763341021) [ 327.814251] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 19:57:07 (1763341027) [ 329.035102] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 330.449921] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 19:57:10 (1763341030) [ 336.198174] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 19:57:15 (1763341035) [ 341.334714] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 19:57:21 (1763341041) [ 347.258485] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 19:57:26 (1763341046) [ 350.176290] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 20s idle [ 350.180425] Lustre: Skipped 1 previous similar message [ 352.123884] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 19:57:31 (1763341051) [ 358.526661] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 19:57:37 (1763341057) [ 365.146470] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 19:57:45 (1763341065) [ 365.536220] Lustre: lustre-OST0001-osc-ffff8e4348744000: disconnect after 23s idle [ 371.277662] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 19:57:50 (1763341070) [ 378.079535] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 19:57:57 (1763341077) [ 383.154324] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 19:58:03 (1763341083) [ 389.968832] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 19:58:09 (1763341089) [ 395.671783] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 19:58:15 (1763341095) [ 401.465273] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 19:58:21 (1763341101) [ 402.033957] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 548.506915] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 20:00:48 (1763341248) [ 555.869979] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 20:00:55 (1763341255) [ 562.067868] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 20:01:01 (1763341261) [ 567.523644] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 20:01:07 (1763341267) [ 573.376929] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 20:01:13 (1763341273) [ 578.993513] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 20:01:18 (1763341278) [ 580.422311] Lustre: DEBUG MARKER: chmod [ 585.752042] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 20:01:25 (1763341285) [ 617.980685] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 631.782919] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 20:02:11 (1763341331) [ 685.729721] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 20:03:05 (1763341385) [ 711.227677] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:03:30 (1763341410) [ 713.696685] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 715.326945] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:03:34 (1763341414) [ 757.773296] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:04:17 (1763341457) [ 759.784170] Lustre: lustre-OST0001-osc-ffff8e4348744000: disconnect after 24s idle [ 764.880302] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:04:24 (1763341464) [ 766.220699] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.299700] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.419983] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.552641] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.646043] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.726421] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.817070] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 766.918319] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.023038] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.114214] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.210141] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.300698] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.424483] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.547845] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.670469] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.765505] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.853113] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 767.949236] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.010522] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.097121] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.168767] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.266047] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.328672] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.420647] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.519979] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.603359] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.704709] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.790285] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.915620] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 768.985045] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.075837] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.138861] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.241783] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.344708] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.430534] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.520547] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.598885] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.669286] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.745994] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.813860] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.895661] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 769.996959] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.100751] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.163242] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.245217] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.332236] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.447986] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.552193] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.654631] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.768396] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.888920] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 770.989546] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.074989] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.157463] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.241115] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.328907] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.384261] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.455255] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.534739] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.622729] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.711205] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.820934] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 771.920517] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.000467] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.069432] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.124716] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.188817] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.257925] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.324145] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.424364] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.521379] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.621715] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.721826] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.830352] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.907109] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 772.988361] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.086792] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.172113] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.250115] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.363361] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.486805] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.588696] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.697391] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 773.863025] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.006240] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.139524] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.256187] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.348632] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.406302] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.516723] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.613711] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.672528] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.769397] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.814095] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 774.927449] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.064796] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.119781] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.207786] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.287055] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.340532] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.489074] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.585853] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.651040] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.812385] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 775.954836] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.062270] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.162395] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.260092] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.367418] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.453950] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.528884] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.589378] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.700517] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.797991] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.886860] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 776.960329] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.011164] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.093127] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.217678] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.333374] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.446187] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.523828] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.593948] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.693701] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.764755] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.876128] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 777.979846] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.081668] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.188974] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.282278] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.371413] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.491303] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.613881] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.704877] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.827561] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 778.957516] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.041587] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.088445] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.138634] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.234235] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.373746] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.516094] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.642872] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.751352] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 779.889169] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.022537] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.081826] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.152322] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.255278] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 20s idle [ 780.283288] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.390668] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.520672] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.589620] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.720670] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 780.889631] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.042414] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.135724] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.299635] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.463249] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.560603] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.656850] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.791733] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 781.948906] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.115610] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.326778] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.432697] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.540165] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.608868] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.685229] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.815880] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.880213] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 782.982192] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.041377] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.146664] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.221218] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.312431] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.373739] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.393210] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.412055] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.431147] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.482983] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.576505] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.662577] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.729946] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.841512] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 783.995048] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.086887] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.196576] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.264916] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.379614] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.488774] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.611950] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.737391] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.817606] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 784.916713] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 785.028890] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 785.125244] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 785.183556] rw_seq_cst_vs_d (29509): drop_caches: 3 [ 794.753938] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:04:53 (1763341493) [ 795.556064] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 795.818837] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.005812] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.264787] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.402537] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.584768] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.806539] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 796.901550] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.087235] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.162042] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.380207] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.598713] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.800059] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 797.915499] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.180886] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.328554] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.426608] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.544100] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.699387] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.777161] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.872799] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 798.963665] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.173606] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.266609] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.443112] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.591735] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.816318] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 799.925717] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.151141] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.404699] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.479215] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.579996] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.718531] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.786297] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 800.886103] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.033061] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.189456] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.441267] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.625603] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.703815] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 801.845706] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.075355] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.130829] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.236669] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.316218] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.421312] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.515821] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.629419] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 802.870053] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.018247] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.047872] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.135753] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.174339] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.368334] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.514947] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.560549] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.685741] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 803.895881] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.088781] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.113400] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.420317] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.535723] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.811483] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 804.933434] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.159957] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.265263] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.476612] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.561763] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.635711] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.728563] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.854952] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 805.859941] Lustre: lustre-OST0001-osc-ffff8e4348744000: disconnect after 20s idle [ 805.862929] Lustre: Skipped 1 previous similar message [ 805.985042] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.088673] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.225920] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.303261] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.416626] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.450697] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.592036] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.705482] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.738936] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.794736] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.891200] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.924323] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 806.985779] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 807.113317] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 807.415512] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 807.544687] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 807.752908] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 807.844744] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.015595] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.125942] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.276664] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.344560] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.437119] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.528510] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.655749] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.751851] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.823619] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 808.980246] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.119335] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.182133] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.274310] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.534083] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.769971] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.835591] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.885025] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 809.953415] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.046164] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.078380] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.249251] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.325753] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.349942] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.553405] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.595118] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.646344] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.721825] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 810.946391] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.042530] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.205728] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.330886] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.428659] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.504169] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 811.694767] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.035315] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.094361] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.184790] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.319310] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.422373] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.473366] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.613232] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.854987] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.882324] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 812.913355] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.084079] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.158957] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.277673] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.357471] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.488509] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.716080] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.895125] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 813.968184] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.204370] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.250221] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.334185] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.385156] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.426837] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.517121] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.577846] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.784037] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 814.955642] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 815.041750] rw_seq_cst_vs_d (30090): drop_caches: 3 [ 822.486305] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:05:22 (1763341522) [ 831.180173] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:05:30 (1763341530) [ 838.917756] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:05:38 (1763341538) [ 875.215380] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:06:14 (1763341574) [ 875.739943] bash (32539): drop_caches: 3 [ 878.909847] bash (32539): drop_caches: 3 [ 882.380203] bash (32539): drop_caches: 3 [ 886.540643] bash (32539): drop_caches: 3 [ 889.757739] bash (32539): drop_caches: 3 [ 892.952863] bash (32539): drop_caches: 3 [ 896.138063] bash (32539): drop_caches: 3 [ 899.292023] bash (32539): drop_caches: 3 [ 902.457970] bash (32539): drop_caches: 3 [ 905.622667] bash (32539): drop_caches: 3 [ 908.792115] bash (32539): drop_caches: 3 [ 911.974219] bash (32539): drop_caches: 3 [ 915.134752] bash (32539): drop_caches: 3 [ 918.376384] bash (32539): drop_caches: 3 [ 921.522200] bash (32539): drop_caches: 3 [ 924.818325] bash (32539): drop_caches: 3 [ 930.652294] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:07:09 (1763341629) [ 940.138175] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:07:20 (1763341640) [ 954.336679] Lustre: lustre-OST0001-osc-ffff8e4348744000: disconnect after 23s idle [ 954.339367] Lustre: Skipped 1 previous similar message [ 1066.327572] LustreError: lustre-OST0000-osc-ffff8e434547f800: operation ost_write to node 192.168.204.138@tcp failed: rc = -107 [ 1066.335738] Lustre: lustre-OST0000-osc-ffff8e434547f800: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1066.365709] LustreError: lustre-OST0000-osc-ffff8e434547f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1066.392283] Lustre: 2358:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.138@tcp:/lustre/fid: [0x200000401:0x6e:0x0]// may get corrupted (rc -5) [ 1066.410684] Lustre: lustre-OST0000-osc-ffff8e434547f800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 1066.418435] LustreError: lustre-OST0001-osc-ffff8e4348744000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1066.418756] Lustre: 2360:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.138@tcp:/lustre/fid: [0x200000401:0x6d:0x0]// may get corrupted (rc -5) [ 1081.807738] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:09:41 (1763341781) [ 1084.224591] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1085.977897] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:09:45 (1763341785) [ 1092.576494] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 21s idle [ 1093.626545] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:09:53 (1763341793) [ 1100.731162] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:10:00 (1763341800) [ 1170.170850] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:11:09 (1763341869) [ 1179.203993] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:11:18 (1763341878) [ 1185.524771] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:11:25 (1763341885) [ 1192.873632] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:11:31 (1763341891) [ 1194.336169] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1196.271601] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:11:35 (1763341895) [ 1204.420050] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:11:44 (1763341904) [ 1213.560458] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:11:53 (1763341913) [ 1221.725545] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1223.556202] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:12:02 (1763341922) [ 1233.160082] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:12:12 (1763341932) [ 1233.662821] Lustre: *** cfs_fail_loc=314, val=0*** [ 1234.719380] Lustre: *** cfs_fail_loc=314, val=0*** [ 1234.721274] Lustre: Skipped 2 previous similar messages [ 1241.838090] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:12:21 (1763341941) [ 1255.752237] Lustre: *** cfs_fail_loc=314, val=0*** [ 1256.435613] Lustre: lustre-OST0000-osc-ffff8e4348744000: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1256.448178] Lustre: Skipped 1 previous similar message [ 1256.468056] LustreError: lustre-OST0000-osc-ffff8e4348744000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1256.493924] LustreError: 41808:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8e4348744000: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8e435931c700) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1256.521775] Lustre: lustre-OST0000-osc-ffff8e4348744000: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 1256.528427] Lustre: Skipped 1 previous similar message [ 1261.848678] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:12:41 (1763341961) [ 1262.285332] LustreError: 42381:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1265.319199] LustreError: 42381:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1270.982537] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:12:50 (1763341970) [ 1277.752806] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:12:57 (1763341977) [ 1284.574406] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1285.949162] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:13:05 (1763341985) [ 1287.475934] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1288.987309] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:13:08 (1763341988) [ 1290.205429] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1291.689306] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:13:11 (1763341991) [ 1292.852535] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1294.326336] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:13:14 (1763341994) [ 1295.689879] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1297.378716] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:13:16 (1763341996) [ 1352.622401] Lustre: lustre-OST0000-osc-ffff8e4348744000: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1352.668706] LustreError: lustre-OST0000-osc-ffff8e4348744000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1352.680967] LustreError: lustre-OST0000-osc-ffff8e434547f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1352.682388] Lustre: lustre-OST0000-osc-ffff8e4348744000: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 1352.706825] Lustre: Skipped 1 previous similar message [ 1369.063983] Lustre: lustre-OST0001-osc-ffff8e434547f800: Connection to lustre-OST0001 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1369.094825] Lustre: Skipped 1 previous similar message [ 1369.117446] LustreError: lustre-OST0001-osc-ffff8e434547f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1369.151430] Lustre: lustre-OST0001-osc-ffff8e434547f800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 1374.175410] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 22s idle [ 1374.186541] Lustre: Skipped 6 previous similar messages [ 1387.328857] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid 50 [ 1388.695566] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1391.927231] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid 50 [ 1393.239644] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1397.642557] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid 50 [ 1399.359330] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1402.621123] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid 50 [ 1403.933328] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1412.997115] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid 50 [ 1414.396252] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1417.647244] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid 50 [ 1419.085808] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8e434547f800.ost_server_uuid in IDLE state after 0 sec [ 1421.129930] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:15:20 (1763342120) [ 1423.793970] Lustre: DEBUG MARKER: Race attempt 0 [ 1426.490216] Lustre: DEBUG MARKER: Wait for 49009 49057 for 60 sec... [ 1492.618806] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:16:32 (1763342192) [ 1499.961352] Lustre: DEBUG MARKER: start test - cycle (0) [ 1526.167097] Lustre: DEBUG MARKER: start test - cycle (1) [ 1552.375454] Lustre: DEBUG MARKER: start test - cycle (2) [ 1579.526317] Lustre: DEBUG MARKER: start test - cycle (3) [ 1603.591059] Lustre: DEBUG MARKER: start test - cycle (4) [ 1630.280790] Lustre: DEBUG MARKER: start test - cycle (5) [ 1635.295358] Lustre: lustre-OST0000-osc-ffff8e4348744000: disconnect after 24s idle [ 1635.298202] Lustre: Skipped 8 previous similar messages [ 1654.263302] Lustre: DEBUG MARKER: start test - cycle (6) [ 1680.859141] Lustre: DEBUG MARKER: start test - cycle (7) [ 1706.384651] Lustre: DEBUG MARKER: start test - cycle (8) [ 1731.355736] Lustre: DEBUG MARKER: start test - cycle (9) [ 1757.136811] Lustre: DEBUG MARKER: start test - cycle (10) [ 1786.532850] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:21:26 (1763342486) [ 1872.743815] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:22:52 (1763342572) [ 1879.636198] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:22:59 (1763342579) [ 1887.863459] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:23:07 (1763342587) [ 1896.575937] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:23:16 (1763342596) [ 1897.069091] Lustre: *** cfs_fail_loc=411, val=0*** [ 1902.957806] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1904.468852] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:23:24 (1763342604) [ 1920.670205] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:23:40 (1763342620) [ 1935.442233] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:23:54 (1763342634) [ 1950.096931] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:24:09 (1763342649) [ 1965.061727] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:24:24 (1763342664) [ 1976.788753] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:24:36 (1763342676) [ 1988.365563] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:24:47 (1763342687) [ 2000.331247] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:24:59 (1763342699) [ 2012.422332] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:25:12 (1763342712) [ 2023.708939] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:25:23 (1763342723) [ 2034.689395] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:25:34 (1763342734) [ 2045.574327] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:25:45 (1763342745) [ 2057.387951] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:25:56 (1763342756) [ 2674.655358] Lustre: lustre-OST0000-osc-ffff8e4348744000: disconnect after 21s idle [ 2674.666069] Lustre: Skipped 14 previous similar messages [ 3050.654969] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 20:42:30 (1763343750) [ 3062.532202] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 20:42:42 (1763343762) [ 3074.495514] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 20:42:54 (1763343774) [ 3086.472431] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 20:43:05 (1763343785) [ 3098.790833] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 20:43:18 (1763343798) [ 3110.711199] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 20:43:30 (1763343810) [ 3121.968628] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 20:43:41 (1763343821) [ 3132.886636] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 20:43:52 (1763343832) [ 3144.506418] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 20:44:04 (1763343844) [ 3197.667838] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 20:44:57 (1763343897) [ 3207.176556] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 20:45:07 (1763343907) [ 3216.293349] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 20:45:16 (1763343916) [ 3226.441177] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 20:45:26 (1763343926) [ 3236.354466] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 20:45:36 (1763343936) [ 3246.207723] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 20:45:45 (1763343945) [ 3256.319871] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 20:45:56 (1763343956) [ 3266.028365] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 20:46:06 (1763343966) [ 3267.112877] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3268.459764] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 20:46:08 (1763343968) [ 3283.942668] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 21s idle [ 3283.945577] Lustre: Skipped 5 previous similar messages [ 3381.548551] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 20:48:01 (1763344081) [ 4365.197831] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:04:24 (1763345064) [ 4369.378071] Lustre: lustre-OST0001-osc-ffff8e4348744000: disconnect after 20s idle [ 4369.406030] Lustre: Skipped 5 previous similar messages [ 4377.472511] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:04:37 (1763345077) [ 4388.462641] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:04:48 (1763345088) [ 4400.022036] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:04:59 (1763345099) [ 4410.370940] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:05:10 (1763345110) [ 4422.255514] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:05:21 (1763345121) [ 4433.941808] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:05:33 (1763345133) [ 4446.002127] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:05:45 (1763345145) [ 4457.351996] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:05:57 (1763345157) [ 4458.523646] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4460.012934] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:05:59 (1763345159) [ 4569.592412] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:07:49 (1763345269) [ 4580.145432] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:07:59 (1763345279) [ 4589.996623] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:08:09 (1763345289) [ 4601.052490] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:08:20 (1763345300) [ 4611.362889] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:08:31 (1763345311) [ 4621.493435] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:08:41 (1763345321) [ 4633.209173] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:08:52 (1763345332) [ 4645.897444] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:09:05 (1763345345) [ 4647.547340] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4649.268699] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:09:08 (1763345348) [ 5024.735499] Lustre: lustre-OST0000-osc-ffff8e434547f800: disconnect after 21s idle [ 5024.741290] Lustre: Skipped 10 previous similar messages [ 5694.941168] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 21:26:34 (1763346394) [ 5708.456810] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 21:26:48 (1763346408) [ 5710.819641] Lustre: lustre-OST0000-osc-ffff8e4348744000: disconnect after 24s idle [ 5710.822082] Lustre: Skipped 3 previous similar messages [ 5720.564278] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 21:27:00 (1763346420) [ 5732.791886] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 21:27:12 (1763346432) [ 5744.970655] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 21:27:24 (1763346444) [ 5756.276150] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 21:27:36 (1763346456) [ 5767.128794] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 21:27:46 (1763346466) [ 5778.890101] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 21:27:58 (1763346478) [ 5791.238365] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 21:28:10 (1763346490) [ 5792.704291] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5794.184442] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 21:28:14 (1763346494) [ 5795.382063] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5797.120828] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 21:28:16 (1763346496) [ 5798.420291] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5799.891365] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 21:28:19 (1763346499) [ 5801.194724] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5802.685392] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 21:28:22 (1763346502) [ 5803.956283] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5805.482117] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 21:28:25 (1763346505) [ 5806.865618] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5808.532236] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 21:28:28 (1763346508) [ 5810.222962] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5812.028087] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 21:28:31 (1763346511) [ 5813.630475] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5815.203497] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 21:28:34 (1763346514) [ 5815.513096] LustreError: 13571:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5817.599440] LustreError: 13571:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5826.326840] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 21:28:45 (1763346525) [ 5835.496334] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 21:28:54 (1763346534) [ 5835.838215] LustreError: 216738:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5839.895956] LustreError: 216738:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5839.936820] LustreError: 216738:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5843.999472] LustreError: 216738:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5844.039212] LustreError: 216744:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5848.119179] LustreError: 216744:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5855.374603] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 21:29:15 (1763346555) [ 5867.093310] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 21:29:26 (1763346566) [ 5874.503738] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 21:29:34 (1763346574) [ 5883.779808] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 21:29:43 (1763346583) [ 5915.455686] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 21:30:14 (1763346614) [ 5927.449048] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 21:30:27 (1763346627) [ 5940.268678] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 21:30:39 (1763346639) [ 5959.043196] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 21:30:58 (1763346658) [ 5974.734703] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 21:31:14 (1763346674) [ 5976.139675] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5977.748120] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 21:31:17 (1763346677) [ 5994.953711] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 21:31:34 (1763346694) [ 6014.487800] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 21:31:54 (1763346714) [ 6120.232380] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 21:33:39 (1763346819) [ 6221.889565] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 21:35:21 (1763346921) [ 6229.099344] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6236.188451] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 21:35:35 (1763346935) [ 6243.818327] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 21:35:43 (1763346943) [ 6245.461848] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6247.337097] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 21:35:46 (1763346946) [ 6249.128561] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6250.925525] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 21:35:50 (1763346950) [ 6252.298737] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 6254.013689] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 21:35:53 (1763346953) [ 6255.598417] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6257.445779] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 21:35:56 (1763346956) [ 6263.393691] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 21:36:03 (1763346963) [ 6269.552966] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 21:36:09 (1763346969) [ 6272.887701] LustreError: lustre-MDT0000-mdc-ffff8e434547f800: operation ldlm_enqueue to node 192.168.204.138@tcp failed: rc = -35 [ 6272.903043] LustreError: Skipped 1 previous similar message [ 6279.885272] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 21:36:19 (1763346979) [ 6280.413224] LustreError: 2359:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6282.503126] LustreError: 2359:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6292.058337] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 21:36:31 (1763346991) [ 6348.356567] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 21:37:28 (1763347048) [ 6355.407094] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 21:37:35 (1763347055) [ 6367.463360] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 21:37:47 (1763347067) [ 6371.295435] Lustre: lustre-OST0000-osc-ffff8e4348744000: disconnect after 22s idle [ 6371.297873] Lustre: Skipped 6 previous similar messages [ 6380.876971] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 21:38:00 (1763347080) [ 6394.001890] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 21:38:13 (1763347093) [ 6413.419209] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 21:38:33 (1763347113) [ 6438.063864] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 21:38:57 (1763347137) [ 6447.533477] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 21:39:07 (1763347147) [ 6456.925503] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 21:39:16 (1763347156) [ 6475.619787] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 21:39:35 (1763347175) [ 6538.028729] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 21:40:37 (1763347237) [ 6686.173480] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:43:05 (1763347385) [ 6835.153899] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:45:34 (1763347534) [ 6975.457818] Lustre: lustre-OST0001-osc-ffff8e434547f800: disconnect after 20s idle [ 6975.460477] Lustre: Skipped 17 previous similar messages [ 7237.772955] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:52:17 (1763347937) [ 7285.311132] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:53:05 (1763347985) [ 7333.204350] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:53:53 (1763348033) [ 7373.124897] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:54:32 (1763348072) [ 7458.855533] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:55:59 (1763348159) [ 7525.276978] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:57:05 (1763348225) [ 7584.740167] Lustre: lustre-OST0001-osc-ffff8e434547f800: disconnect after 21s idle [ 7584.745052] Lustre: Skipped 16 previous similar messages [ 7649.405669] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:59:09 (1763348349) [ 7652.496784] LustreError: 256902:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434547f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7652.509847] LustreError: 256902:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7652.536088] Lustre: Unmounted lustre-client [ 7654.146070] LustreError: 256915:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4348744000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7654.150967] LustreError: 256915:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7654.156536] LustreError: 256915:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7654.161461] LustreError: 256915:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 7654.181428] Lustre: Unmounted lustre-client [ 7726.930681] Lustre: Mounted lustre-client [ 7729.076649] Lustre: Mounted lustre-client [ 7731.094372] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7829.537778] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 22:02:09 (1763348529) [ 7843.438815] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:02:23 (1763348543) [ 7851.037693] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:02:30 (1763348550) [ 7904.717806] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:03:24 (1763348604) [ 7982.925123] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:04:42 (1763348682) [ 7991.823642] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:04:51 (1763348691) [ 8076.325463] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:06:16 (1763348776) [ 8101.768190] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 22:06:41 (1763348801) [ 8149.935552] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:07:29 (1763348849) [ 8157.498392] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:07:37 (1763348857) [ 8175.397510] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:07:54 (1763348874) [ 8176.462688] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 8177.757713] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:07:57 (1763348877) [ 8178.888414] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 8180.267155] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:08:00 (1763348880) [ 8181.798279] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 8183.220695] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:08:02 (1763348882) [ 8184.713686] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 8186.403643] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:08:06 (1763348886) [ 8187.539221] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 8189.281208] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 22:08:08 (1763348888) [ 8307.589847] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:10:07 (1763349007) [ 8314.125256] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:10:13 (1763349013) [ 8315.488176] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 8317.077979] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:10:16 (1763349016) [ 8328.160050] Lustre: lustre-OST0000-osc-ffff8e43608cf800: disconnect after 24s idle [ 8328.162833] Lustre: Skipped 15 previous similar messages [ 8329.632139] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 22:10:28 (1763349028) [ 8342.114756] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:10:41 (1763349041) [ 8343.349781] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 8344.951583] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:10:44 (1763349044) [ 8346.187890] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 8347.665526] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:10:47 (1763349047) [ 8349.014947] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 8350.648524] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:10:50 (1763349050) [ 8365.284486] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:11:05 (1763349065) [ 8365.630618] Lustre: DEBUG MARKER: write [ 8365.683242] LustreError: 269269:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8367.694241] Lustre: DEBUG MARKER: kill 282950 [ 8367.698983] LustreError: 282950:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8370.783215] LustreError: 269269:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8373.751145] LustreError: 282950:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8378.912940] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 22:11:18 (1763349078) [ 8381.594615] LustreError: 283555:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8391.639207] LustreError: 283555:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8399.804140] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:11:39 (1763349099) [ 8400.228520] LustreError: 284136:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8402.319134] LustreError: 284136:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [ 8413.051548] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:11:52 (1763349112) [ 8414.595498] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8416.594733] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:11:55 (1763349115) [ 8423.538088] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:12:02 (1763349122) [ 8430.952836] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:12:10 (1763349130) [ 8436.518110] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:12:16 (1763349136) [ 8442.664195] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:12:22 (1763349142) [ 8449.623761] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:12:29 (1763349149) [ 8456.302550] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:12:35 (1763349155) [ 8464.029658] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 8465.836207] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:12:45 (1763349165) [ 8467.314308] Lustre: *** cfs_fail_loc=415, val=0*** [ 8477.735113] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:12:57 (1763349177) [ 8479.200518] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 8480.800621] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:13:00 (1763349180) [ 8481.042513] LustreError: 258319:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8481.046156] LustreError: 258319:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 8486.055164] LustreError: 258997:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8486.062208] LustreError: 258997:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [ 8496.087164] LustreError: 258319:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8496.102316] LustreError: 258319:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 8503.071536] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 22:13:22 (1763349202) [ 8504.290739] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 8505.862690] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 22:13:25 (1763349205) [ 8513.274124] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 22:13:32 (1763349212) [ 8520.505951] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 22:13:39 (1763349219) [ 8529.330127] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:13:49 (1763349229) [ 8542.421620] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:14:02 (1763349242) [ 8542.915793] LustreError: 288226:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8542.920261] LustreError: 288226:0:(osc_request.c:2943:osc_build_rpc()) Skipped 6 previous similar messages [ 8547.007144] LustreError: 288226:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8547.010223] LustreError: 288226:0:(osc_request.c:2943:osc_build_rpc()) Skipped 1 previous similar message [ 8553.939788] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:14:13 (1763349253) [ 8555.977586] LustreError: 294091:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4345880000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8555.992180] LustreError: 294091:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8556.013322] LustreError: 294091:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8556.015754] LustreError: 294091:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8556.073825] Lustre: Unmounted lustre-client [ 8557.298675] LustreError: 294112:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43608cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8557.316951] LustreError: 294112:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8557.330497] LustreError: 294112:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8557.342692] LustreError: 294112:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 8557.402728] Lustre: Unmounted lustre-client [ 8558.713516] Lustre: DEBUG MARKER: Iteration 1 [ 8558.972457] LustreError: 294271:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8558.976263] LustreError: 294273:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8558.983711] LustreError: 294271:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 8559.204111] Lustre: Mounted lustre-client [ 8559.207239] Lustre: Skipped 1 previous similar message [ 8560.439585] LustreError: 294376:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4360973800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8560.444085] LustreError: 294376:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8560.464982] LustreError: 294376:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8560.480707] LustreError: 294376:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 8560.511439] Lustre: Unmounted lustre-client [ 8563.116625] Key type lgssc unregistered [ 8563.431322] LNet: 294621:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8563.447839] LNetError: 294621:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8564.521705] LNet: Removed LNI 192.168.204.38@tcp [ 8565.385251] Key type .llcrypt unregistered [ 8565.391711] Key type ._llcrypt unregistered [ 8566.313936] Key type ._llcrypt registered [ 8566.320280] Key type .llcrypt registered [ 8566.637306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8566.652841] alg: No test for adler32 (adler32-zlib) [ 8567.899051] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8568.763513] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8570.543266] Key type lgssc registered [ 8571.677972] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8582.448591] Lustre: DEBUG MARKER: Iteration 2 [ 8582.805895] LustreError: 295439:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8582.807237] LustreError: 295448:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8582.814218] LustreError: 295439:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8584.043341] Lustre: Mounted lustre-client [ 8585.052708] LustreError: 295542:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4344ec4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8585.064110] LustreError: 295542:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8585.092341] Lustre: Unmounted lustre-client [ 8587.079935] Key type lgssc unregistered [ 8587.299337] LNet: 295790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8587.306980] LNetError: 295790:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8587.326281] LNet: Removed LNI 192.168.204.38@tcp [ 8587.922832] Key type .llcrypt unregistered [ 8587.927398] Key type ._llcrypt unregistered [ 8588.521171] Key type ._llcrypt registered [ 8588.522457] Key type .llcrypt registered [ 8588.918282] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8588.925707] alg: No test for adler32 (adler32-zlib) [ 8589.988762] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8590.209877] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8591.903978] Key type lgssc registered [ 8593.009598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8601.121313] Lustre: DEBUG MARKER: Iteration 3 [ 8601.402505] LustreError: 296608:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8601.405604] LustreError: 296609:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8601.410950] LustreError: 296608:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8602.590754] Lustre: Mounted lustre-client [ 8603.609975] LustreError: 296710:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4346478800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8603.637797] LustreError: 296710:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8603.663376] Lustre: Unmounted lustre-client [ 8605.719188] Key type lgssc unregistered [ 8605.903777] LNet: 296960:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8605.910534] LNetError: 296960:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8605.925990] LNet: Removed LNI 192.168.204.38@tcp [ 8606.378200] Key type .llcrypt unregistered [ 8606.379858] Key type ._llcrypt unregistered [ 8606.875188] Key type ._llcrypt registered [ 8606.879171] Key type .llcrypt registered [ 8607.262713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8607.276994] alg: No test for adler32 (adler32-zlib) [ 8608.261345] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8608.457680] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8610.114430] Key type lgssc registered [ 8610.865384] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8619.157131] Lustre: DEBUG MARKER: Iteration 4 [ 8619.566143] LustreError: 297779:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8619.570623] LustreError: 297780:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8619.577292] LustreError: 297779:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8620.875204] Lustre: Mounted lustre-client [ 8621.938577] LustreError: 297889:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434547a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8621.950965] LustreError: 297889:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8621.956933] LustreError: 297890:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8621.976096] Lustre: Unmounted lustre-client [ 8624.253260] Key type lgssc unregistered [ 8624.469689] LNet: 298132:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8624.477105] LNetError: 298132:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8624.501187] LNet: Removed LNI 192.168.204.38@tcp [ 8625.056311] Key type .llcrypt unregistered [ 8625.061397] Key type ._llcrypt unregistered [ 8625.627161] Key type ._llcrypt registered [ 8625.629121] Key type .llcrypt registered [ 8626.074665] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8626.095825] alg: No test for adler32 (adler32-zlib) [ 8627.176404] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8627.367679] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8629.031197] Key type lgssc registered [ 8629.983349] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8637.390659] Lustre: DEBUG MARKER: Iteration 5 [ 8637.593860] LustreError: 298949:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8637.594267] LustreError: 298950:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8637.600444] LustreError: 298949:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8638.784077] Lustre: Mounted lustre-client [ 8639.546259] LustreError: 299056:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43500f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8639.562099] LustreError: 299056:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8639.601610] Lustre: Unmounted lustre-client [ 8641.631022] Key type lgssc unregistered [ 8641.847307] LNet: 299300:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8641.859637] LNetError: 299300:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8641.884539] LNet: Removed LNI 192.168.204.38@tcp [ 8642.416438] Key type .llcrypt unregistered [ 8642.423501] Key type ._llcrypt unregistered [ 8643.092722] Key type ._llcrypt registered [ 8643.094777] Key type .llcrypt registered [ 8643.498350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8643.508782] alg: No test for adler32 (adler32-zlib) [ 8644.525797] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8644.719182] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8646.359309] Key type lgssc registered [ 8647.894648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8655.729698] Lustre: DEBUG MARKER: Iteration 6 [ 8655.997658] LustreError: 300119:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8656.001077] LustreError: 300121:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8656.005565] LustreError: 300119:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8657.191731] Lustre: Mounted lustre-client [ 8657.193189] Lustre: Skipped 1 previous similar message [ 8658.085661] LustreError: 300223:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434569d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8658.113895] LustreError: 300223:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8658.142111] Lustre: Unmounted lustre-client [ 8660.135261] Key type lgssc unregistered [ 8660.388517] LNet: 300472:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8660.400555] LNetError: 300472:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8660.422848] LNet: Removed LNI 192.168.204.38@tcp [ 8661.073165] Key type .llcrypt unregistered [ 8661.077955] Key type ._llcrypt unregistered [ 8661.594293] Key type ._llcrypt registered [ 8661.596553] Key type .llcrypt registered [ 8661.947333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8661.957787] alg: No test for adler32 (adler32-zlib) [ 8663.003501] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8663.205795] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8664.863245] Key type lgssc registered [ 8665.898989] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8674.275063] Lustre: DEBUG MARKER: Iteration 7 [ 8674.541195] LustreError: 301290:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8674.545197] LustreError: 301289:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8674.557468] LustreError: 301290:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8675.789352] Lustre: Mounted lustre-client [ 8676.867494] LustreError: 301398:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4345a98800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8676.888393] LustreError: 301398:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8676.925434] Lustre: Unmounted lustre-client [ 8678.991267] Key type lgssc unregistered [ 8679.202759] LNet: 301646:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8679.228166] LNetError: 301646:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8680.233146] LNet: Removed LNI 192.168.204.38@tcp [ 8680.711173] Key type .llcrypt unregistered [ 8680.713052] Key type ._llcrypt unregistered [ 8681.066215] Key type ._llcrypt registered [ 8681.067673] Key type .llcrypt registered [ 8681.359228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8681.405428] alg: No test for adler32 (adler32-zlib) [ 8682.435261] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8682.618792] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8684.255188] Key type lgssc registered [ 8685.226754] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8693.451839] Lustre: DEBUG MARKER: Iteration 8 [ 8693.625199] LustreError: 302461:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8693.626171] LustreError: 302466:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8693.633420] LustreError: 302461:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8694.802239] Lustre: Mounted lustre-client [ 8695.666719] LustreError: 302567:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e437b248000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8695.682588] LustreError: 302567:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8695.701137] Lustre: Unmounted lustre-client [ 8697.884722] Key type lgssc unregistered [ 8698.120356] LNet: 302817:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8698.123554] LNetError: 302817:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8698.140703] LNet: Removed LNI 192.168.204.38@tcp [ 8698.724166] Key type .llcrypt unregistered [ 8698.726396] Key type ._llcrypt unregistered [ 8699.277151] Key type ._llcrypt registered [ 8699.278860] Key type .llcrypt registered [ 8699.658880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8699.684650] alg: No test for adler32 (adler32-zlib) [ 8700.768227] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8701.083578] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8702.791151] Key type lgssc registered [ 8703.796510] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8713.060682] Lustre: DEBUG MARKER: Iteration 9 [ 8713.374758] LustreError: 303634:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8713.374865] LustreError: 303633:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8713.386902] LustreError: 303634:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8715.623857] Lustre: Mounted lustre-client [ 8716.678693] LustreError: 303744:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4360974000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8716.695124] LustreError: 303744:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8716.722416] Lustre: Unmounted lustre-client [ 8719.087721] Key type lgssc unregistered [ 8719.306771] LNet: 303984:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8719.316725] LNetError: 303984:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8719.338255] LNet: Removed LNI 192.168.204.38@tcp [ 8720.015090] Key type .llcrypt unregistered [ 8720.016707] Key type ._llcrypt unregistered [ 8720.818592] Key type ._llcrypt registered [ 8720.822153] Key type .llcrypt registered [ 8721.332623] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8721.351646] alg: No test for adler32 (adler32-zlib) [ 8722.621741] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8722.829390] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8724.503191] Key type lgssc registered [ 8725.770559] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8736.665980] Lustre: DEBUG MARKER: Iteration 10 [ 8736.962956] LustreError: 304803:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8736.966313] LustreError: 304804:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8736.978694] LustreError: 304803:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8738.193922] Lustre: Mounted lustre-client [ 8739.214834] LustreError: 304907:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436ac54000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8739.230789] LustreError: 304907:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8739.279251] Lustre: Unmounted lustre-client [ 8741.578764] Key type lgssc unregistered [ 8741.837845] LNet: 305155:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8741.849704] LNetError: 305155:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8741.875726] LNet: Removed LNI 192.168.204.38@tcp [ 8742.451189] Key type .llcrypt unregistered [ 8742.453062] Key type ._llcrypt unregistered [ 8743.114332] Key type ._llcrypt registered [ 8743.115830] Key type .llcrypt registered [ 8743.621152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8743.634537] alg: No test for adler32 (adler32-zlib) [ 8744.671427] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8744.848982] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8746.547436] Key type lgssc registered [ 8747.817259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8758.691722] Lustre: DEBUG MARKER: Iteration 11 [ 8759.061984] LustreError: 305970:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8759.082682] LustreError: 305988:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8759.088817] LustreError: 305970:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [ 8760.345671] Lustre: Mounted lustre-client [ 8761.534413] LustreError: 306083:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434569f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8761.543860] LustreError: 306083:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8761.554808] LustreError: 306083:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8761.558257] LustreError: 306083:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8761.586363] Lustre: Unmounted lustre-client [ 8763.642337] Key type lgssc unregistered [ 8763.848420] LNet: 306325:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8763.856762] LNetError: 306325:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8763.869239] LNet: Removed LNI 192.168.204.38@tcp [ 8764.381531] Key type .llcrypt unregistered [ 8764.383249] Key type ._llcrypt unregistered [ 8765.090133] Key type ._llcrypt registered [ 8765.092167] Key type .llcrypt registered [ 8765.355079] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8765.372144] alg: No test for adler32 (adler32-zlib) [ 8766.327831] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8766.489666] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8768.152484] Key type lgssc registered [ 8769.294507] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8778.666840] Lustre: DEBUG MARKER: Iteration 12 [ 8779.070067] LustreError: 307144:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8779.079350] LustreError: 307145:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8779.089626] LustreError: 307144:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [ 8780.331972] Lustre: Mounted lustre-client [ 8781.272466] LustreError: 307247:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436ac55000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8781.311964] LustreError: 307247:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8781.348965] Lustre: Unmounted lustre-client [ 8783.737787] Key type lgssc unregistered [ 8783.996975] LNet: 307494:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8784.013221] LNetError: 307494:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8785.066951] LNet: Removed LNI 192.168.204.38@tcp [ 8785.625316] Key type .llcrypt unregistered [ 8785.627078] Key type ._llcrypt unregistered [ 8786.346438] Key type ._llcrypt registered [ 8786.348042] Key type .llcrypt registered [ 8786.657275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8786.679838] alg: No test for adler32 (adler32-zlib) [ 8787.821385] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8788.146854] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8789.792658] Key type lgssc registered [ 8791.099411] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8800.303734] Lustre: DEBUG MARKER: Iteration 13 [ 8800.709265] LustreError: 308313:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8800.710609] LustreError: 308314:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8800.722351] LustreError: 308313:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8801.962872] Lustre: Mounted lustre-client [ 8801.964394] Lustre: Skipped 1 previous similar message [ 8803.445172] LustreError: 308416:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43797a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8803.463588] LustreError: 308416:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8803.510977] Lustre: Unmounted lustre-client [ 8806.184527] Key type lgssc unregistered [ 8806.455929] LNet: 308663:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8806.474065] LNetError: 308663:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8806.509075] LNet: Removed LNI 192.168.204.38@tcp [ 8807.284471] Key type .llcrypt unregistered [ 8807.288959] Key type ._llcrypt unregistered [ 8807.970700] Key type ._llcrypt registered [ 8807.972244] Key type .llcrypt registered [ 8808.483738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8808.500475] alg: No test for adler32 (adler32-zlib) [ 8809.541310] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8809.780677] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8811.487251] Key type lgssc registered [ 8812.536088] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8821.508357] Lustre: DEBUG MARKER: Iteration 14 [ 8821.806704] LustreError: 309483:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8821.809421] LustreError: 309484:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8821.836518] LustreError: 309483:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8823.052660] Lustre: Mounted lustre-client [ 8824.042562] LustreError: 309585:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43500f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8824.075888] LustreError: 309585:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8824.109329] Lustre: Unmounted lustre-client [ 8826.487874] Key type lgssc unregistered [ 8826.755713] LNet: 309832:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8826.769106] LNetError: 309832:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8826.790533] LNet: Removed LNI 192.168.204.38@tcp [ 8827.356601] Key type .llcrypt unregistered [ 8827.358233] Key type ._llcrypt unregistered [ 8828.024074] Key type ._llcrypt registered [ 8828.027167] Key type .llcrypt registered [ 8828.239686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8828.247908] alg: No test for adler32 (adler32-zlib) [ 8829.233286] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8829.387231] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8831.019753] Key type lgssc registered [ 8832.032622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8841.489867] Lustre: DEBUG MARKER: Iteration 15 [ 8841.708967] LustreError: 310656:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8841.710037] LustreError: 310642:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8841.722470] LustreError: 310656:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8842.907909] Lustre: Mounted lustre-client [ 8842.917760] Lustre: Skipped 1 previous similar message [ 8843.908097] LustreError: 310762:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4344ee4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8843.929949] LustreError: 310762:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8843.959724] Lustre: Unmounted lustre-client [ 8846.017713] Key type lgssc unregistered [ 8846.232760] LNet: 311005:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8846.243807] LNetError: 311005:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8846.263395] LNet: Removed LNI 192.168.204.38@tcp [ 8846.790653] Key type .llcrypt unregistered [ 8846.793623] Key type ._llcrypt unregistered [ 8847.342776] Key type ._llcrypt registered [ 8847.344785] Key type .llcrypt registered [ 8847.710929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8847.722651] alg: No test for adler32 (adler32-zlib) [ 8848.765715] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8848.932169] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8850.559200] Key type lgssc registered [ 8851.382560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8858.728352] Lustre: DEBUG MARKER: Iteration 16 [ 8858.934599] LustreError: 311823:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8858.937980] LustreError: 311824:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8858.942472] LustreError: 311823:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8860.103602] Lustre: Mounted lustre-client [ 8860.107234] Lustre: Skipped 1 previous similar message [ 8861.177487] LustreError: 311928:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434569a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8861.184670] LustreError: 311928:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8861.187779] LustreError: 311936:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8861.214406] Lustre: Unmounted lustre-client [ 8863.088163] Key type lgssc unregistered [ 8863.344337] LNet: 312176:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8863.349638] LNetError: 312176:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8863.362609] LNet: Removed LNI 192.168.204.38@tcp [ 8863.847189] Key type .llcrypt unregistered [ 8863.849725] Key type ._llcrypt unregistered [ 8864.349098] Key type ._llcrypt registered [ 8864.351206] Key type .llcrypt registered [ 8864.663255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8864.707057] alg: No test for adler32 (adler32-zlib) [ 8865.711550] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8865.971380] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8867.647178] Key type lgssc registered [ 8868.780484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8877.034326] Lustre: DEBUG MARKER: Iteration 17 [ 8877.381502] LustreError: 312995:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8877.385779] LustreError: 312994:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8877.390591] LustreError: 312995:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8878.577677] Lustre: Mounted lustre-client [ 8879.752422] LustreError: 313104:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436ac50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8879.774436] LustreError: 313104:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8879.816796] Lustre: Unmounted lustre-client [ 8881.832321] Key type lgssc unregistered [ 8882.033924] LNet: 313345:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8882.040117] LNetError: 313345:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8883.112033] LNet: Removed LNI 192.168.204.38@tcp [ 8883.671174] Key type .llcrypt unregistered [ 8883.673452] Key type ._llcrypt unregistered [ 8884.125393] Key type ._llcrypt registered [ 8884.162824] Key type .llcrypt registered [ 8884.378057] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8884.389230] alg: No test for adler32 (adler32-zlib) [ 8885.396912] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8885.578085] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8887.215195] Key type lgssc registered [ 8887.998621] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8896.410131] Lustre: DEBUG MARKER: Iteration 18 [ 8896.609589] LustreError: 314163:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8896.610814] LustreError: 314165:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8896.620220] LustreError: 314163:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8897.843990] Lustre: Mounted lustre-client [ 8897.846774] Lustre: Skipped 1 previous similar message [ 8898.711744] LustreError: 314265:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8898.724418] LustreError: 314265:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8898.752110] Lustre: Unmounted lustre-client [ 8900.524743] Key type lgssc unregistered [ 8900.712169] LNet: 314513:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8900.722273] LNetError: 314513:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8900.739073] LNet: Removed LNI 192.168.204.38@tcp [ 8901.129239] Key type .llcrypt unregistered [ 8901.130821] Key type ._llcrypt unregistered [ 8901.507200] Key type ._llcrypt registered [ 8901.508936] Key type .llcrypt registered [ 8901.818799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8901.827209] alg: No test for adler32 (adler32-zlib) [ 8902.803335] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8902.997954] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8904.631177] Key type lgssc registered [ 8905.708420] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8914.316453] Lustre: DEBUG MARKER: Iteration 19 [ 8914.536685] LustreError: 315331:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8914.536933] LustreError: 315332:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8914.545655] LustreError: 315331:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8915.742304] Lustre: Mounted lustre-client [ 8916.597590] LustreError: 315441:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43762c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8916.607951] LustreError: 315442:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8916.611197] LustreError: 315442:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8916.630614] Lustre: Unmounted lustre-client [ 8918.335158] Key type lgssc unregistered [ 8918.515902] LNet: 315682:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8918.520709] LNetError: 315682:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8918.540027] LNet: Removed LNI 192.168.204.38@tcp [ 8919.016544] Key type .llcrypt unregistered [ 8919.019220] Key type ._llcrypt unregistered [ 8919.725127] Key type ._llcrypt registered [ 8919.745052] Key type .llcrypt registered [ 8919.993170] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8920.011900] alg: No test for adler32 (adler32-zlib) [ 8921.037243] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8921.215944] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8922.863249] Key type lgssc registered [ 8923.755429] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8932.316972] Lustre: DEBUG MARKER: Iteration 20 [ 8932.753895] LustreError: 316502:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8932.755983] LustreError: 316501:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8932.777926] LustreError: 316502:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 8935.128334] Lustre: Mounted lustre-client [ 8936.204546] LustreError: 316605:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43797ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8936.228824] LustreError: 316605:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8936.289348] Lustre: Unmounted lustre-client [ 8939.027340] Key type lgssc unregistered [ 8939.291521] LNet: 316851:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8939.340333] LNetError: 316851:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8940.393334] LNet: Removed LNI 192.168.204.38@tcp [ 8941.026359] Key type .llcrypt unregistered [ 8941.028345] Key type ._llcrypt unregistered [ 8941.670430] Key type ._llcrypt registered [ 8941.674667] Key type .llcrypt registered [ 8942.115847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8942.135574] alg: No test for adler32 (adler32-zlib) [ 8943.183510] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8943.370841] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8945.047239] Key type lgssc registered [ 8946.319803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8956.186273] Lustre: DEBUG MARKER: Iteration 21 [ 8956.541160] LustreError: 317667:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8956.542159] LustreError: 317672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8956.548586] LustreError: 317667:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8957.783581] Lustre: Mounted lustre-client [ 8959.079821] LustreError: 317777:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4343f5d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8959.095268] LustreError: 317777:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8959.099925] LustreError: 317777:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8959.125469] Lustre: Unmounted lustre-client [ 8961.583484] Key type lgssc unregistered [ 8961.846091] LNet: 318018:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8961.855749] LNetError: 318018:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8961.877539] LNet: Removed LNI 192.168.204.38@tcp [ 8962.425186] Key type .llcrypt unregistered [ 8962.431340] Key type ._llcrypt unregistered [ 8963.674648] Key type ._llcrypt registered [ 8963.677870] Key type .llcrypt registered [ 8963.922579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8963.943252] alg: No test for adler32 (adler32-zlib) [ 8965.030392] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8965.368883] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8967.119179] Key type lgssc registered [ 8968.246435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8979.005418] Lustre: DEBUG MARKER: Iteration 22 [ 8979.555187] LustreError: 318837:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8979.558413] LustreError: 318839:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8979.565762] LustreError: 318837:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8980.880810] Lustre: Mounted lustre-client [ 8982.059368] LustreError: 318943:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8982.082457] LustreError: 318943:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8982.129568] Lustre: Unmounted lustre-client [ 8984.130430] Key type lgssc unregistered [ 8984.329982] LNet: 319193:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8984.333992] LNetError: 319193:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8984.349642] LNet: Removed LNI 192.168.204.38@tcp [ 8984.851838] Key type .llcrypt unregistered [ 8984.853515] Key type ._llcrypt unregistered [ 8985.404108] Key type ._llcrypt registered [ 8985.405859] Key type .llcrypt registered [ 8985.754092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8985.767897] alg: No test for adler32 (adler32-zlib) [ 8986.745252] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 8986.921867] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 8988.569320] Key type lgssc registered [ 8989.746644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8998.539450] Lustre: DEBUG MARKER: Iteration 23 [ 8998.766489] LustreError: 320012:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8998.767238] LustreError: 320013:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8998.775452] LustreError: 320012:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8999.930583] Lustre: Mounted lustre-client [ 8999.943475] Lustre: Skipped 1 previous similar message [ 9001.008325] LustreError: 320112:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4344e9e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9001.034590] LustreError: 320112:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9001.075466] Lustre: Unmounted lustre-client [ 9003.099080] Key type lgssc unregistered [ 9003.329515] LNet: 320359:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9003.336549] LNetError: 320359:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9003.349638] LNet: Removed LNI 192.168.204.38@tcp [ 9003.891293] Key type .llcrypt unregistered [ 9003.892923] Key type ._llcrypt unregistered [ 9004.580348] Key type ._llcrypt registered [ 9004.581962] Key type .llcrypt registered [ 9004.928799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9004.943772] alg: No test for adler32 (adler32-zlib) [ 9005.991162] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9006.256692] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9007.943169] Key type lgssc registered [ 9009.098697] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9018.792117] Lustre: DEBUG MARKER: Iteration 24 [ 9019.186853] LustreError: 321174:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9019.209503] LustreError: 321188:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9019.213862] LustreError: 321174:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 9020.485777] Lustre: Mounted lustre-client [ 9021.742845] LustreError: 321280:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434569b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9021.793204] LustreError: 321280:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9021.837951] Lustre: Unmounted lustre-client [ 9024.353895] Key type lgssc unregistered [ 9024.611641] LNet: 321529:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9024.620257] LNetError: 321529:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9024.641426] LNet: Removed LNI 192.168.204.38@tcp [ 9025.271226] Key type .llcrypt unregistered [ 9025.272662] Key type ._llcrypt unregistered [ 9025.930275] Key type ._llcrypt registered [ 9025.931820] Key type .llcrypt registered [ 9026.346625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9026.359394] alg: No test for adler32 (adler32-zlib) [ 9027.425271] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9027.759301] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9029.422099] Key type lgssc registered [ 9030.645519] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9040.588593] Lustre: DEBUG MARKER: Iteration 25 [ 9040.838532] LustreError: 322350:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9040.843348] LustreError: 322349:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9040.847970] LustreError: 322350:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9042.035336] Lustre: Mounted lustre-client [ 9043.009140] LustreError: 322458:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4345a9a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9043.017788] LustreError: 322458:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9043.017878] LustreError: 322459:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9043.060735] Lustre: Unmounted lustre-client [ 9045.556133] Key type lgssc unregistered [ 9045.817519] LNet: 322700:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9045.831196] LNetError: 322700:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9045.859205] LNet: Removed LNI 192.168.204.38@tcp [ 9046.675303] Key type .llcrypt unregistered [ 9046.676833] Key type ._llcrypt unregistered [ 9047.479521] Key type ._llcrypt registered [ 9047.482242] Key type .llcrypt registered [ 9048.038052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9048.058749] alg: No test for adler32 (adler32-zlib) [ 9049.183709] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9049.461876] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9051.255955] Key type lgssc registered [ 9052.437527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9063.445836] Lustre: DEBUG MARKER: Iteration 26 [ 9063.957839] LustreError: 323518:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9063.961908] LustreError: 323520:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9063.973104] LustreError: 323518:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9065.209274] Lustre: Mounted lustre-client [ 9066.473313] LustreError: 323619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43500fe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9066.487123] LustreError: 323619:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9066.519606] Lustre: Unmounted lustre-client [ 9069.091424] Key type lgssc unregistered [ 9069.359818] LNet: 323867:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9069.363107] LNetError: 323867:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9069.376954] LNet: Removed LNI 192.168.204.38@tcp [ 9070.131282] Key type .llcrypt unregistered [ 9070.133661] Key type ._llcrypt unregistered [ 9070.902375] Key type ._llcrypt registered [ 9070.903907] Key type .llcrypt registered [ 9071.289345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9071.310777] alg: No test for adler32 (adler32-zlib) [ 9072.486588] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9072.705776] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9074.423205] Key type lgssc registered [ 9075.651707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9087.650522] Lustre: DEBUG MARKER: Iteration 27 [ 9087.960525] LustreError: 324683:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9087.963093] LustreError: 324687:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9087.978843] LustreError: 324683:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [ 9089.231096] Lustre: Mounted lustre-client [ 9089.232596] Lustre: Skipped 1 previous similar message [ 9090.482271] LustreError: 324791:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43715dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9090.516637] LustreError: 324791:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9090.549786] Lustre: Unmounted lustre-client [ 9092.863752] Key type lgssc unregistered [ 9093.152784] LNet: 325039:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9093.160319] LNetError: 325039:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9093.173220] LNet: Removed LNI 192.168.204.38@tcp [ 9093.670621] Key type .llcrypt unregistered [ 9093.672270] Key type ._llcrypt unregistered [ 9094.243223] Key type ._llcrypt registered [ 9094.245087] Key type .llcrypt registered [ 9094.624046] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9094.646105] alg: No test for adler32 (adler32-zlib) [ 9095.687840] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9095.914261] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9097.623810] Key type lgssc registered [ 9098.730482] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9108.313537] Lustre: DEBUG MARKER: Iteration 28 [ 9108.674825] LustreError: 325867:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9108.689414] LustreError: 325877:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9108.692364] LustreError: 325867:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [ 9109.971966] Lustre: Mounted lustre-client [ 9111.128361] LustreError: 325984:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9111.138402] LustreError: 325983:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9111.138503] LustreError: 325984:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9111.175432] Lustre: Unmounted lustre-client [ 9113.519877] Key type lgssc unregistered [ 9113.770705] LNet: 326224:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9113.783904] LNetError: 326224:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9113.808079] LNet: Removed LNI 192.168.204.38@tcp [ 9114.505332] Key type .llcrypt unregistered [ 9114.507598] Key type ._llcrypt unregistered [ 9115.175436] Key type ._llcrypt registered [ 9115.217190] Key type .llcrypt registered [ 9115.613963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9115.626194] alg: No test for adler32 (adler32-zlib) [ 9116.717291] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9116.883191] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9118.535501] Key type lgssc registered [ 9119.536542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9128.088906] Lustre: DEBUG MARKER: Iteration 29 [ 9128.353868] LustreError: 327042:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9128.355188] LustreError: 327044:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9128.370059] LustreError: 327042:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9129.603654] Lustre: Mounted lustre-client [ 9130.713660] LustreError: 327146:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43455bf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9130.729306] LustreError: 327146:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9130.762910] Lustre: Unmounted lustre-client [ 9132.884555] Key type lgssc unregistered [ 9133.066824] LNet: 327396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9133.070371] LNetError: 327396:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9133.083885] LNet: Removed LNI 192.168.204.38@tcp [ 9133.694327] Key type .llcrypt unregistered [ 9133.697192] Key type ._llcrypt unregistered [ 9134.289629] Key type ._llcrypt registered [ 9134.290833] Key type .llcrypt registered [ 9134.711795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9134.727536] alg: No test for adler32 (adler32-zlib) [ 9135.773815] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9135.988580] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9137.679153] Key type lgssc registered [ 9138.710314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9147.640289] Lustre: DEBUG MARKER: Iteration 30 [ 9147.906818] LustreError: 328212:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9147.919262] LustreError: 328221:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9147.924563] LustreError: 328212:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 9149.164448] Lustre: Mounted lustre-client [ 9150.227869] LustreError: 328317:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9150.243089] LustreError: 328317:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9150.292134] Lustre: Unmounted lustre-client [ 9152.541088] Key type lgssc unregistered [ 9152.740811] LNet: 328565:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9152.748169] LNetError: 328565:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9152.763368] LNet: Removed LNI 192.168.204.38@tcp [ 9153.238779] Key type .llcrypt unregistered [ 9153.241237] Key type ._llcrypt unregistered [ 9153.810885] Key type ._llcrypt registered [ 9153.812359] Key type .llcrypt registered [ 9154.355370] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9154.369397] alg: No test for adler32 (adler32-zlib) [ 9155.513986] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9155.778616] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9157.495373] Key type lgssc registered [ 9159.151644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9168.920924] Lustre: DEBUG MARKER: Iteration 31 [ 9169.199923] LustreError: 329384:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9169.216040] LustreError: 329385:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9169.223902] LustreError: 329384:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [ 9171.436502] Lustre: Mounted lustre-client [ 9172.729262] LustreError: 329488:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43797a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9172.750560] LustreError: 329488:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9172.794263] Lustre: Unmounted lustre-client [ 9175.377411] Key type lgssc unregistered [ 9175.660052] LNet: 329735:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9175.671197] LNetError: 329735:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9175.691022] LNet: Removed LNI 192.168.204.38@tcp [ 9176.430223] Key type .llcrypt unregistered [ 9176.433603] Key type ._llcrypt unregistered [ 9177.337633] Key type ._llcrypt registered [ 9177.354159] Key type .llcrypt registered [ 9177.677575] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9177.691529] alg: No test for adler32 (adler32-zlib) [ 9178.773284] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9179.038772] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9180.743197] Key type lgssc registered [ 9181.871476] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9191.628274] Lustre: DEBUG MARKER: Iteration 32 [ 9191.966619] LustreError: 330550:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9191.970417] LustreError: 330558:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9191.985992] LustreError: 330550:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9193.276993] Lustre: Mounted lustre-client [ 9194.747272] LustreError: 330660:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43723e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9194.780161] LustreError: 330660:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9194.831462] Lustre: Unmounted lustre-client [ 9197.706817] Key type lgssc unregistered [ 9197.938685] LNet: 330907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9197.951162] LNetError: 330907:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9197.972374] LNet: Removed LNI 192.168.204.38@tcp [ 9198.569292] Key type .llcrypt unregistered [ 9198.571070] Key type ._llcrypt unregistered [ 9199.184894] Key type ._llcrypt registered [ 9199.204085] Key type .llcrypt registered [ 9199.568339] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9199.580251] alg: No test for adler32 (adler32-zlib) [ 9200.655726] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9200.940710] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9202.671193] Key type lgssc registered [ 9203.908480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9214.687795] Lustre: DEBUG MARKER: Iteration 33 [ 9215.195168] LustreError: 331726:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9215.199404] LustreError: 331728:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9215.204497] LustreError: 331726:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9216.448134] Lustre: Mounted lustre-client [ 9217.763378] LustreError: 331831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434a945800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9217.793931] LustreError: 331831:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9217.828158] Lustre: Unmounted lustre-client [ 9220.113293] Key type lgssc unregistered [ 9220.374074] LNet: 332077:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9220.385147] LNetError: 332077:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9220.413638] LNet: Removed LNI 192.168.204.38@tcp [ 9221.042178] Key type .llcrypt unregistered [ 9221.045443] Key type ._llcrypt unregistered [ 9221.783371] Key type ._llcrypt registered [ 9221.784962] Key type .llcrypt registered [ 9222.132052] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9222.168924] alg: No test for adler32 (adler32-zlib) [ 9223.282849] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9223.493312] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9225.175198] Key type lgssc registered [ 9226.326116] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9236.254839] Lustre: DEBUG MARKER: Iteration 34 [ 9236.613972] LustreError: 332892:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9236.631595] LustreError: 332901:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9236.638607] LustreError: 332892:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9237.982816] Lustre: Mounted lustre-client [ 9239.192192] LustreError: 333002:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434569f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9239.213270] LustreError: 333002:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9239.249337] Lustre: Unmounted lustre-client [ 9241.787354] Key type lgssc unregistered [ 9242.023040] LNet: 333251:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9242.033169] LNetError: 333251:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9242.053505] LNet: Removed LNI 192.168.204.38@tcp [ 9242.661292] Key type .llcrypt unregistered [ 9242.663143] Key type ._llcrypt unregistered [ 9243.293784] Key type ._llcrypt registered [ 9243.296600] Key type .llcrypt registered [ 9243.606125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9243.652353] alg: No test for adler32 (adler32-zlib) [ 9244.800162] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9245.012427] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9246.671187] Key type lgssc registered [ 9247.836705] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9259.012210] Lustre: DEBUG MARKER: Iteration 35 [ 9259.366244] LustreError: 334064:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9259.409556] LustreError: 334078:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9259.426301] LustreError: 334064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4952 [ 9260.756435] Lustre: Mounted lustre-client [ 9260.762523] Lustre: Skipped 1 previous similar message [ 9261.851972] LustreError: 334168:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4376119000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9261.886994] LustreError: 334176:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9261.895150] LustreError: 334176:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9261.922174] Lustre: Unmounted lustre-client [ 9264.192537] Key type lgssc unregistered [ 9264.405555] LNet: 334416:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9264.411391] LNetError: 334416:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9264.424286] LNet: Removed LNI 192.168.204.38@tcp [ 9265.008317] Key type .llcrypt unregistered [ 9265.011632] Key type ._llcrypt unregistered [ 9265.718991] Key type ._llcrypt registered [ 9265.722026] Key type .llcrypt registered [ 9265.964197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9265.976213] alg: No test for adler32 (adler32-zlib) [ 9267.063450] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9267.253185] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9268.920563] Key type lgssc registered [ 9270.042878] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9280.216582] Lustre: DEBUG MARKER: Iteration 36 [ 9280.506430] LustreError: 335235:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9280.507222] LustreError: 335236:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9280.533341] LustreError: 335235:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4975 [ 9282.842612] Lustre: Mounted lustre-client [ 9283.895850] LustreError: 335343:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434a924000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9283.913811] LustreError: 335343:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9283.922124] LustreError: 335344:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9283.952574] Lustre: Unmounted lustre-client [ 9286.017434] Key type lgssc unregistered [ 9286.233561] LNet: 335584:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9286.240454] LNetError: 335584:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9286.256543] LNet: Removed LNI 192.168.204.38@tcp [ 9286.810180] Key type .llcrypt unregistered [ 9286.815043] Key type ._llcrypt unregistered [ 9287.421150] Key type ._llcrypt registered [ 9287.422758] Key type .llcrypt registered [ 9287.785917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9287.795445] alg: No test for adler32 (adler32-zlib) [ 9288.802712] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9288.991264] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9290.655343] Key type lgssc registered [ 9291.488804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9300.425788] Lustre: DEBUG MARKER: Iteration 37 [ 9300.753758] LustreError: 336400:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9300.754866] LustreError: 336401:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9300.763871] LustreError: 336400:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9301.962493] Lustre: Mounted lustre-client [ 9303.354359] LustreError: 336503:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434595e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9303.374849] LustreError: 336503:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9303.418431] Lustre: Unmounted lustre-client [ 9305.844664] Key type lgssc unregistered [ 9306.091464] LNet: 336749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9306.100594] LNetError: 336749:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9306.122211] LNet: Removed LNI 192.168.204.38@tcp [ 9306.715181] Key type .llcrypt unregistered [ 9306.721115] Key type ._llcrypt unregistered [ 9307.485272] Key type ._llcrypt registered [ 9307.486682] Key type .llcrypt registered [ 9307.850115] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9307.879631] alg: No test for adler32 (adler32-zlib) [ 9308.946319] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9309.247325] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9310.927231] Key type lgssc registered [ 9311.958687] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9321.234430] Lustre: DEBUG MARKER: Iteration 38 [ 9321.545396] LustreError: 337563:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9321.562555] LustreError: 337577:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9321.571425] LustreError: 337563:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4978 [ 9322.816714] Lustre: Mounted lustre-client [ 9323.907620] LustreError: 337671:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4369bd2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9323.931866] LustreError: 337671:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9323.968489] Lustre: Unmounted lustre-client [ 9325.991649] Key type lgssc unregistered [ 9326.229637] LNet: 337920:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9326.241112] LNetError: 337920:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9326.260497] LNet: Removed LNI 192.168.204.38@tcp [ 9326.740229] Key type .llcrypt unregistered [ 9326.741852] Key type ._llcrypt unregistered [ 9327.296964] Key type ._llcrypt registered [ 9327.308140] Key type .llcrypt registered [ 9327.701580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9327.717296] alg: No test for adler32 (adler32-zlib) [ 9328.858945] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9329.083745] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9330.735222] Key type lgssc registered [ 9331.904215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9340.308501] Lustre: DEBUG MARKER: Iteration 39 [ 9340.518117] LustreError: 338740:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9340.518245] LustreError: 338738:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9340.531217] LustreError: 338740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9342.734135] Lustre: Mounted lustre-client [ 9343.683365] LustreError: 338845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434a945800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9343.722568] LustreError: 338845:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9343.771335] Lustre: Unmounted lustre-client [ 9345.828227] Key type lgssc unregistered [ 9346.011280] LNet: 339094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9346.015966] LNetError: 339094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9347.049390] LNet: Removed LNI 192.168.204.38@tcp [ 9347.676284] Key type .llcrypt unregistered [ 9347.677861] Key type ._llcrypt unregistered [ 9348.347573] Key type ._llcrypt registered [ 9348.352989] Key type .llcrypt registered [ 9348.618082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9348.632768] alg: No test for adler32 (adler32-zlib) [ 9349.671376] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9349.908306] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9351.579868] Key type lgssc registered [ 9352.556627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9360.841202] Lustre: DEBUG MARKER: Iteration 40 [ 9361.172897] LustreError: 339912:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9361.175483] LustreError: 339913:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9361.185391] LustreError: 339912:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9362.466474] Lustre: Mounted lustre-client [ 9362.476665] Lustre: Skipped 1 previous similar message [ 9363.510424] LustreError: 340021:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9363.547171] LustreError: 340021:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9363.600135] Lustre: Unmounted lustre-client [ 9365.801245] Key type lgssc unregistered [ 9366.106968] LNet: 340263:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9366.121784] LNetError: 340263:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9366.139252] LNet: Removed LNI 192.168.204.38@tcp [ 9366.796331] Key type .llcrypt unregistered [ 9366.801634] Key type ._llcrypt unregistered [ 9367.523949] Key type ._llcrypt registered [ 9367.525664] Key type .llcrypt registered [ 9368.002393] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9368.023300] alg: No test for adler32 (adler32-zlib) [ 9369.085239] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9369.359310] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9371.023368] Key type lgssc registered [ 9372.237606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9382.883963] Lustre: DEBUG MARKER: Iteration 41 [ 9383.243617] LustreError: 341083:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9383.244433] LustreError: 341084:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9383.253304] LustreError: 341083:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9384.511496] Lustre: Mounted lustre-client [ 9385.640379] LustreError: 341190:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e437ae94800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9385.649779] LustreError: 341190:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9385.657481] LustreError: 341193:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9385.696058] Lustre: Unmounted lustre-client [ 9387.958732] Key type lgssc unregistered [ 9388.205288] LNet: 341434:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9388.219142] LNetError: 341434:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9388.238564] LNet: Removed LNI 192.168.204.38@tcp [ 9388.878343] Key type .llcrypt unregistered [ 9388.880095] Key type ._llcrypt unregistered [ 9389.560389] Key type ._llcrypt registered [ 9389.567574] Key type .llcrypt registered [ 9390.131138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9390.143276] alg: No test for adler32 (adler32-zlib) [ 9391.196708] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9391.414159] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9393.143250] Key type lgssc registered [ 9394.332444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9404.259548] Lustre: DEBUG MARKER: Iteration 42 [ 9404.608350] LustreError: 342253:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9404.615360] LustreError: 342255:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9404.626968] LustreError: 342253:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 9405.889269] Lustre: Mounted lustre-client [ 9407.057764] LustreError: 342363:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9407.066478] LustreError: 342363:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9407.131275] Lustre: Unmounted lustre-client [ 9409.597563] Key type lgssc unregistered [ 9409.878853] LNet: 342603:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9409.889165] LNetError: 342603:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9409.903169] LNet: Removed LNI 192.168.204.38@tcp [ 9410.639942] Key type .llcrypt unregistered [ 9410.641759] Key type ._llcrypt unregistered [ 9411.707292] Key type ._llcrypt registered [ 9411.709143] Key type .llcrypt registered [ 9411.888279] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9411.905424] alg: No test for adler32 (adler32-zlib) [ 9412.987196] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9413.262575] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9414.919404] Key type lgssc registered [ 9416.194142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9427.825630] Lustre: DEBUG MARKER: Iteration 43 [ 9428.321170] LustreError: 343422:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9428.324566] LustreError: 343424:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9428.335709] LustreError: 343422:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 9429.637114] Lustre: Mounted lustre-client [ 9430.942908] LustreError: 343533:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436097f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9430.970209] LustreError: 343533:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9430.974390] LustreError: 343533:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9431.004361] Lustre: Unmounted lustre-client [ 9433.844787] Key type lgssc unregistered [ 9434.096129] LNet: 343773:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9434.102815] LNetError: 343773:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9434.111575] LNet: Removed LNI 192.168.204.38@tcp [ 9434.820175] Key type .llcrypt unregistered [ 9434.821937] Key type ._llcrypt unregistered [ 9435.583919] Key type ._llcrypt registered [ 9435.585376] Key type .llcrypt registered [ 9435.897808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9435.909351] alg: No test for adler32 (adler32-zlib) [ 9437.053803] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9437.287476] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9438.927167] Key type lgssc registered [ 9440.051552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9449.596839] Lustre: DEBUG MARKER: Iteration 44 [ 9449.971579] LustreError: 344588:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9449.982867] LustreError: 344598:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9449.994363] LustreError: 344588:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [ 9452.237532] Lustre: Mounted lustre-client [ 9453.292877] LustreError: 344695:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4345479800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9453.318869] LustreError: 344695:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9453.347435] Lustre: Unmounted lustre-client [ 9455.465531] Key type lgssc unregistered [ 9455.661984] LNet: 344944:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9455.673808] LNetError: 344944:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9455.712405] LNet: Removed LNI 192.168.204.38@tcp [ 9456.231173] Key type .llcrypt unregistered [ 9456.234159] Key type ._llcrypt unregistered [ 9456.686870] Key type ._llcrypt registered [ 9456.688592] Key type .llcrypt registered [ 9457.027736] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9457.039202] alg: No test for adler32 (adler32-zlib) [ 9458.066653] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9458.300434] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9459.991167] Key type lgssc registered [ 9461.132402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9471.003441] Lustre: DEBUG MARKER: Iteration 45 [ 9471.399571] LustreError: 345762:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9471.404924] LustreError: 345764:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9471.415134] LustreError: 345762:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9472.729719] Lustre: Mounted lustre-client [ 9473.964651] LustreError: 345863:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43454c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9474.039784] LustreError: 345863:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9474.086420] Lustre: Unmounted lustre-client [ 9476.689342] Key type lgssc unregistered [ 9477.028943] LNet: 346112:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9477.037271] LNetError: 346112:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9477.051596] LNet: Removed LNI 192.168.204.38@tcp [ 9477.738239] Key type .llcrypt unregistered [ 9477.741938] Key type ._llcrypt unregistered [ 9478.602967] Key type ._llcrypt registered [ 9478.604521] Key type .llcrypt registered [ 9479.098389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9479.116768] alg: No test for adler32 (adler32-zlib) [ 9480.145761] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9480.379334] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9482.095209] Key type lgssc registered [ 9483.238986] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9494.667100] Lustre: DEBUG MARKER: Iteration 46 [ 9495.201296] LustreError: 346932:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9495.204122] LustreError: 346933:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9495.231484] LustreError: 346932:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9496.518138] Lustre: Mounted lustre-client [ 9496.519669] Lustre: Skipped 1 previous similar message [ 9497.911495] LustreError: 347035:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43714fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9497.933724] LustreError: 347035:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9497.977677] Lustre: Unmounted lustre-client [ 9500.255956] Key type lgssc unregistered [ 9500.568842] LNet: 347285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9500.582060] LNetError: 347285:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9500.601136] LNet: Removed LNI 192.168.204.38@tcp [ 9501.128611] Key type .llcrypt unregistered [ 9501.130958] Key type ._llcrypt unregistered [ 9501.694860] Key type ._llcrypt registered [ 9501.697410] Key type .llcrypt registered [ 9502.100120] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9502.117172] alg: No test for adler32 (adler32-zlib) [ 9503.224568] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9503.452547] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9505.159179] Key type lgssc registered [ 9506.376304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9516.846518] Lustre: DEBUG MARKER: Iteration 47 [ 9517.209130] LustreError: 348102:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9517.212603] LustreError: 348105:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9517.224895] LustreError: 348102:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9518.487220] Lustre: Mounted lustre-client [ 9519.715056] LustreError: 348214:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4344e9a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9519.728158] LustreError: 348213:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9519.728879] LustreError: 348214:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9519.751167] Lustre: Unmounted lustre-client [ 9522.373985] Key type lgssc unregistered [ 9522.634702] LNet: 348455:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9522.660036] LNetError: 348455:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9523.688627] LNet: Removed LNI 192.168.204.38@tcp [ 9524.481679] Key type .llcrypt unregistered [ 9524.483251] Key type ._llcrypt unregistered [ 9525.415768] Key type ._llcrypt registered [ 9525.426898] Key type .llcrypt registered [ 9526.114778] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9526.140819] alg: No test for adler32 (adler32-zlib) [ 9527.320514] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9527.593155] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9529.279226] Key type lgssc registered [ 9530.754830] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9541.874957] Lustre: DEBUG MARKER: Iteration 48 [ 9542.249027] LustreError: 349276:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9542.252285] LustreError: 349275:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9542.257194] LustreError: 349276:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9543.583147] Lustre: Mounted lustre-client [ 9544.756709] LustreError: 349384:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e434547b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9544.777285] LustreError: 349384:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9544.820040] Lustre: Unmounted lustre-client [ 9547.493276] Key type lgssc unregistered [ 9547.724388] LNet: 349628:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9547.741975] LNetError: 349628:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9547.765040] LNet: Removed LNI 192.168.204.38@tcp [ 9548.372306] Key type .llcrypt unregistered [ 9548.374882] Key type ._llcrypt unregistered [ 9549.471982] Key type ._llcrypt registered [ 9549.473680] Key type .llcrypt registered [ 9549.812672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9549.827529] alg: No test for adler32 (adler32-zlib) [ 9550.995956] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9551.198263] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9552.855284] Key type lgssc registered [ 9554.155333] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9565.962275] Lustre: DEBUG MARKER: Iteration 49 [ 9566.255428] LustreError: 350448:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9566.256358] LustreError: 350449:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9566.275973] LustreError: 350448:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [ 9567.531577] Lustre: Mounted lustre-client [ 9569.592737] LustreError: 350559:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4344e9e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9569.625046] LustreError: 350559:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9569.677302] Lustre: Unmounted lustre-client [ 9572.953470] Key type lgssc unregistered [ 9573.171687] LNet: 350801:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9573.181624] LNetError: 350801:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9573.211099] LNet: Removed LNI 192.168.204.38@tcp [ 9574.002171] Key type .llcrypt unregistered [ 9574.003667] Key type ._llcrypt unregistered [ 9574.626390] Key type ._llcrypt registered [ 9574.629020] Key type .llcrypt registered [ 9575.183478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9575.200652] alg: No test for adler32 (adler32-zlib) [ 9576.243658] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9576.464548] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9578.128820] Key type lgssc registered [ 9579.154097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9589.213798] Lustre: DEBUG MARKER: Iteration 50 [ 9589.604373] LustreError: 351621:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9589.607581] LustreError: 351622:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9589.622292] LustreError: 351621:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 9591.036224] Lustre: Mounted lustre-client [ 9592.690955] LustreError: 351722:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e4342cae800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9592.715032] LustreError: 351722:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9592.747598] Lustre: Unmounted lustre-client [ 9596.307180] Key type lgssc unregistered [ 9596.663877] LNet: 351971:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9596.685871] LNetError: 351971:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9596.726899] LNet: Removed LNI 192.168.204.38@tcp [ 9597.355219] Key type .llcrypt unregistered [ 9597.359179] Key type ._llcrypt unregistered [ 9598.059809] Key type ._llcrypt registered [ 9598.077047] Key type .llcrypt registered [ 9598.551900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9598.563487] alg: No test for adler32 (adler32-zlib) [ 9599.756364] Lustre: Lustre: Build Version: 2.16.61_48_ga3a4329 [ 9599.930927] LNet: Added LNI 192.168.204.38@tcp [8/256/0/180] [ 9601.616694] Key type lgssc registered [ 9602.978494] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9614.828861] Lustre: Mounted lustre-client [ 9621.311708] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:32:01 (1763350321) [ 9638.880131] Lustre: 353305:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350323/real 1763350323] req@ffff8e43800b4380 x1849006804183168/t0(0) o36->lustre-MDT0000-mdc-ffff8e436d5e6800@192.168.204.138@tcp:12/10 lens 496/440 e 0 to 1 dl 1763350339 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9638.920441] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection to lustre-MDT0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9638.954606] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9654.241868] Lustre: 353305:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350339/real 1763350339] req@ffff8e43800b4380 x1849006804183168/t0(0) o36->lustre-MDT0000-mdc-ffff8e436d5e6800@192.168.204.138@tcp:12/10 lens 496/440 e 0 to 1 dl 1763350355 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9654.271372] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection to lustre-MDT0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9654.295712] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9670.623603] Lustre: 353305:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350355/real 1763350355] req@ffff8e43800b4380 x1849006804183168/t0(0) o36->lustre-MDT0000-mdc-ffff8e436d5e6800@192.168.204.138@tcp:12/10 lens 496/440 e 0 to 1 dl 1763350371 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9670.660487] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection to lustre-MDT0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9670.714375] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9687.008438] Lustre: 353305:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350371/real 1763350371] req@ffff8e43800b4380 x1849006804183168/t0(0) o36->lustre-MDT0000-mdc-ffff8e436d5e6800@192.168.204.138@tcp:12/10 lens 496/440 e 0 to 1 dl 1763350387 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9687.028464] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection to lustre-MDT0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9687.073957] Lustre: lustre-MDT0000-mdc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9690.045162] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:33:09 (1763350389) [ 9691.142653] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 9692.675708] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:33:12 (1763350392) [ 9694.325889] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 9696.042323] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:33:15 (1763350395) [ 9702.163576] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:33:21 (1763350401) [ 9703.369308] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 9705.374598] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:33:24 (1763350404) [ 9706.988490] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 9708.775193] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:33:28 (1763350408) [ 9710.309473] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 9712.170402] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:33:31 (1763350411) [ 9733.087418] Lustre: 352164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350418/real 1763350418] req@ffff8e43800b7100 x1849006804212224/t0(0) o4->lustre-OST0000-osc-ffff8e436d5e6800@192.168.204.138@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763350434 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9733.091052] Lustre: lustre-OST0000-osc-ffff8e436d5e6800: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9733.110632] Lustre: 352164:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 9749.471159] Lustre: 352161:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350434/real 1763350434] req@ffff8e43487c5180 x1849006804211840/t0(0) o4->lustre-OST0000-osc-ffff8e436d5e6800@192.168.204.138@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763350450 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9749.471191] Lustre: lustre-OST0000-osc-ffff8e436d5e6800: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9749.488109] Lustre: 352161:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [ 9749.564573] Lustre: lustre-OST0000-osc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9765.855557] Lustre: 352161:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763350450/real 1763350450] req@ffff8e43487c5180 x1849006804211840/t0(0) o4->lustre-OST0000-osc-ffff8e436d5e6800@192.168.204.138@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763350466 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9765.855914] Lustre: lustre-OST0000-osc-ffff8e436d5e6800: Connection to lustre-OST0000 (at 192.168.204.138@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9765.879961] Lustre: 352161:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 9765.914462] Lustre: lustre-OST0000-osc-ffff8e436d5e6800: Connection restored to 192.168.204.138@tcp (at 192.168.204.138@tcp) [ 9787.788296] Lustre: DEBUG MARKER: oleg438-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8e436d5e6800.ost_server_uuid 50 [ 9789.213911] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8e436d5e6800.ost_server_uuid in FULL state after 0 sec [ 9790.717921] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9792.168412] Lustre: DEBUG MARKER: == sanityn test complete, duration 9558 sec ============== 22:34:51 (1763350491) [ 9793.476816] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:34:53 (1763350493) === [10027.739932] LustreError: 357405:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e43797ad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10027.753825] LustreError: 357405:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10027.773442] Lustre: Unmounted lustre-client [10031.253210] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:38:50 (1763350730) === [10032.780699] LustreError: 357707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8e436d5e6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10032.791598] LustreError: 357707:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10032.804950] LustreError: 357707:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [10032.811542] LustreError: 357707:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [10032.879391] Lustre: Unmounted lustre-client [10066.494997] Key type lgssc unregistered [10066.692098] LNet: 358190:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10066.702253] LNetError: 358190:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10066.716815] LNet: Removed LNI 192.168.204.38@tcp [10067.457156] Key type .llcrypt unregistered [10067.459165] Key type ._llcrypt unregistered