[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 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 449604126 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 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.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002019] kvm-guest: setup PV IPIs [ 0.005000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.005000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.005028] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007010] pid_max: default: 32768 minimum: 301 [ 0.008153] LSM: Security Framework initializing [ 0.009074] Yama: becoming mindful. [ 0.010057] SELinux: Initializing. [ 0.012078] *** VALIDATE selinux *** [ 0.022761] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027892] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028181] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029117] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030129] *** VALIDATE tmpfs *** [ 0.031479] *** VALIDATE proc *** [ 0.032319] *** VALIDATE cgroup *** [ 0.033012] *** VALIDATE cgroup2 *** [ 0.034317] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035188] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036014] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037035] Spectre V2 : User space: Vulnerable [ 0.038013] Speculative Store Bypass: Vulnerable [ 0.041397] debug: unmapping init [mem 0xffffffffbaa59000-0xffffffffbaa60fff] [ 0.043183] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044861] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045032] ... version: 2 [ 0.046021] ... bit width: 48 [ 0.047018] ... generic registers: 4 [ 0.048017] ... value mask: 0000ffffffffffff [ 0.049026] ... max period: 00007fffffffffff [ 0.050019] ... fixed-purpose events: 3 [ 0.051012] ... event mask: 000000070000000f [ 0.052355] rcu: Hierarchical SRCU implementation. [ 0.054666] smp: Bringing up secondary CPUs ... [ 0.055681] x86: Booting SMP configuration: [ 0.056045] .... node #0, CPUs: #1 #2 #3 [ 0.067829] smp: Brought up 1 node, 4 CPUs [ 0.069037] smpboot: Max logical packages: 1 [ 0.070013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.315829] node 0 deferred pages initialised in 243ms [ 0.319288] devtmpfs: initialized [ 0.320317] x86/mm: Memory block size: 128MB [ 0.322913] gcov: version magic: 0x41383552 [ 0.324511] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.325101] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.326345] pinctrl core: initialized pinctrl subsystem [ 0.327284] [ 0.327871] ************************************************************* [ 0.328028] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.329021] ** ** [ 0.330031] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.331024] ** ** [ 0.332031] ** This means that this kernel is built to expose internal ** [ 0.333021] ** IOMMU data structures, which may compromise security on ** [ 0.334019] ** your system. ** [ 0.335018] ** ** [ 0.336024] ** If you see this message and you are not debugging the ** [ 0.337020] ** kernel, report this immediately to your vendor! ** [ 0.338024] ** ** [ 0.339027] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.340023] ************************************************************* [ 0.341670] NET: Registered protocol family 16 [ 0.342520] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.343083] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.344083] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.350213] cpuidle: using governor menu [ 0.356221] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.358763] PCI: Using configuration type 1 for base access [ 0.361170] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.372034] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.373036] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.374193] cryptd: max_cpu_qlen set to 1000 [ 0.376346] ACPI: Added _OSI(Module Device) [ 0.377026] ACPI: Added _OSI(Processor Device) [ 0.378020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.379019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.382590] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.386293] ACPI: Interpreter enabled [ 0.387063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.388012] ACPI: Using IOAPIC for interrupt routing [ 0.389180] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.390413] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.399761] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.402064] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.405029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.409115] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.414507] acpiphp: Slot [2] registered [ 0.416208] acpiphp: Slot [5] registered [ 0.417223] acpiphp: Slot [6] registered [ 0.419199] acpiphp: Slot [3] registered [ 0.420165] acpiphp: Slot [4] registered [ 0.422160] acpiphp: Slot [7] registered [ 0.423134] acpiphp: Slot [8] registered [ 0.425098] acpiphp: Slot [9] registered [ 0.426135] acpiphp: Slot [10] registered [ 0.428146] acpiphp: Slot [11] registered [ 0.430208] acpiphp: Slot [12] registered [ 0.431158] acpiphp: Slot [13] registered [ 0.433225] acpiphp: Slot [14] registered [ 0.435155] acpiphp: Slot [15] registered [ 0.436132] acpiphp: Slot [16] registered [ 0.438162] acpiphp: Slot [17] registered [ 0.440173] acpiphp: Slot [18] registered [ 0.441133] acpiphp: Slot [19] registered [ 0.443162] acpiphp: Slot [20] registered [ 0.445143] acpiphp: Slot [21] registered [ 0.446111] acpiphp: Slot [22] registered [ 0.447137] acpiphp: Slot [23] registered [ 0.449125] acpiphp: Slot [24] registered [ 0.451132] acpiphp: Slot [25] registered [ 0.452187] acpiphp: Slot [26] registered [ 0.454139] acpiphp: Slot [27] registered [ 0.456125] acpiphp: Slot [28] registered [ 0.457137] acpiphp: Slot [29] registered [ 0.458121] acpiphp: Slot [30] registered [ 0.460137] acpiphp: Slot [31] registered [ 0.462124] PCI host bridge to bus 0000:00 [ 0.463025] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.466050] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.468052] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.472039] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.475034] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.478033] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.480387] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.484221] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.488534] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.496022] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.500393] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.503032] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.505031] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.508026] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.511083] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.513893] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.517065] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.519847] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.525021] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.536025] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.541021] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.547514] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.553023] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.559029] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.571024] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.581000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.588021] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.593019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.606025] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.613749] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.616589] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.619518] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.622490] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.625291] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.631154] iommu: Default domain type: Passthrough [ 0.634602] SCSI subsystem initialized [ 0.636152] ACPI: bus type USB registered [ 0.637000] usbcore: registered new interface driver usbfs [ 0.637000] usbcore: registered new interface driver hub [ 0.637000] usbcore: registered new device driver usb [ 0.640237] pps_core: LinuxPPS API ver. 1 registered [ 0.642012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.645065] PTP clock support registered [ 0.647182] EDAC MC: Ver: 3.0.0 [ 0.648460] PCI: Using ACPI for IRQ routing [ 0.649759] NetLabel: Initializing [ 0.651021] NetLabel: domain hash size = 128 [ 0.653022] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.655117] NetLabel: unlabeled traffic allowed by default [ 0.657165] vgaarb: loaded [ 0.660204] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.662019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.668006] clocksource: Switched to clocksource kvm-clock [ 0.778853] VFS: Disk quotas dquot_6.6.0 [ 0.780462] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.783302] *** VALIDATE ramfs *** [ 0.784730] *** VALIDATE hugetlbfs *** [ 0.786342] pnp: PnP ACPI init [ 0.788938] pnp: PnP ACPI: found 6 devices [ 0.807557] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.811113] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.813566] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.816206] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.818821] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.821415] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.824713] NET: Registered protocol family 2 [ 0.827291] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.832171] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.836560] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.842799] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.848201] TCP: Hash tables configured (established 65536 bind 65536) [ 0.851042] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.854267] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.857117] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.860397] NET: Registered protocol family 1 [ 0.863178] RPC: Registered named UNIX socket transport module. [ 0.865925] RPC: Registered udp transport module. [ 0.867920] RPC: Registered tcp transport module. [ 0.869729] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.872116] NET: Registered protocol family 44 [ 0.874286] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.876805] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.898984] pci 0000:00:00.0: quirk_passive_release+0x0/0x90 took 20334 usecs [ 0.903045] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.905564] PCI: CLS 0 bytes, default 64 [ 0.908869] Unpacking initramfs... [ 2.375983] debug: unmapping init [mem 0xffff88debcc64000-0xffff88debffcffff] [ 2.381592] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.384180] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.386980] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.900169] Initialise system trusted keyrings [ 2.902052] Key type blacklist registered [ 2.908286] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.922943] zbud: loaded [ 2.926187] *** VALIDATE nfs *** [ 2.927580] *** VALIDATE nfs4 *** [ 2.929698] pstore: using deflate compression [ 2.933070] Platform Keyring initialized [ 3.035800] NET: Registered protocol family 38 [ 3.037725] Key type asymmetric registered [ 3.038924] Asymmetric key parser 'x509' registered [ 3.040668] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.044129] io scheduler mq-deadline registered [ 3.046076] io scheduler kyber registered [ 3.048114] io scheduler bfq registered [ 3.050183] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.053428] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.056663] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.060305] ACPI: Power Button [PWRF] [ 3.151386] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.242604] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.356098] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.389705] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.427543] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.433245] Non-volatile memory driver v1.3 [ 3.435202] Linux agpgart interface v0.103 [ 3.471064] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.474351] vda: detected capacity change from 0 to 68530176 [ 3.491116] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.494547] vdb: detected capacity change from 0 to 1073741824 [ 3.503594] libphy: Fixed MDIO Bus: probed [ 3.510401] usbcore: registered new interface driver usbserial_generic [ 3.513060] usbserial: USB Serial support registered for generic [ 3.515424] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.519523] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.521204] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.524365] mousedev: PS/2 mouse device common for all mice [ 3.528561] rtc_cmos 00:05: RTC can wake from S4 [ 3.532551] rtc_cmos 00:05: registered as rtc0 [ 3.532768] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.534045] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.534091] intel_pstate: CPU model not supported [ 3.536759] hid: raw HID events driver (C) Jiri Kosina [ 3.544570] usbcore: registered new interface driver usbhid [ 3.547109] usbhid: USB HID core driver [ 3.548231] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.549773] drop_monitor: Initializing network drop monitor service [ 3.556582] Initializing XFRM netlink socket [ 3.559432] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.559511] NET: Registered protocol family 10 [ 3.566789] Segment Routing with IPv6 [ 3.568457] NET: Registered protocol family 17 [ 3.570583] mpls_gso: MPLS GSO support [ 3.577139] RAS: Correctable Errors collector initialized. [ 3.579264] AVX version of gcm_enc/dec engaged. [ 3.581111] AES CTR mode by8 optimization enabled [ 3.672229] sched_clock: Marking stable (3672188980, 0)->(4687378717, -1015189737) [ 3.676182] registered taskstats version 1 [ 3.678271] Loading compiled-in X.509 certificates [ 3.680514] zswap: loaded using pool lzo/zbud [ 3.705357] Key type big_key registered [ 3.718908] Key type encrypted registered [ 3.721092] ima: No TPM chip found, activating TPM-bypass! [ 3.723319] ima: Allocated hash algorithm: sha1 [ 3.727904] ima: No architecture policies found [ 3.731268] evm: Initialising EVM extended attributes: [ 3.733440] evm: security.selinux [ 3.734525] evm: security.ima [ 3.735605] evm: security.capability [ 3.736908] evm: HMAC attrs: 0x1 [ 3.740530] rtc_cmos 00:05: setting system clock to 2025-11-17 01:51:42 UTC (1763344302) [ 3.752937] debug: unmapping init [mem 0xffffffffbba03000-0xffffffffbbbfffff] [ 3.755957] debug: unmapping init [mem 0xffffffffba782000-0xffffffffbaa58fff] [ 3.767088] Write protecting the kernel read-only data: 28672k [ 3.770656] debug: unmapping init [mem 0xffffffffb8e03000-0xffffffffb8ffffff] [ 3.773380] debug: unmapping init [mem 0xffffffffb9714000-0xffffffffb97fffff] [ 3.834152] 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.844293] systemd[1]: Detected virtualization kvm. [ 3.846398] systemd[1]: Detected architecture x86-64. [ 3.848767] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.874590] systemd[1]: No hostname configured. [ 3.876641] systemd[1]: Set hostname to . [ 3.878931] random: systemd: uninitialized urandom read (16 bytes read) [ 3.881375] systemd[1]: Initializing machine ID from random generator. [ 3.928584] random: ln: uninitialized urandom read (6 bytes read) [ 4.016199] random: systemd: uninitialized urandom read (16 bytes read) [ 4.019311] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 4.027407] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.032189] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.670177] device-mapper: uevent: version 1.0.3 [ 4.672570] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.441212] virtio_net virtio0 ens2: renamed from eth0 [ 5.494525] scsi host0: ata_piix [ 5.520323] scsi host1: ata_piix [ 5.522109] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.524505] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 7.961078] random: fast init done [ 9.290381] dracut-initqueue[589]: RTNETLINK answers: File exists [ 10.247835] random: crng init done [ 10.249285] 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.756735] 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 dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 12.034094] printk: systemd: 25 output lines suppressed due to ratelimiting [ 12.279585] SELinux: Disabled at runtime. [ 12.350723] 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.359331] systemd[1]: Detected virtualization kvm. [ 12.361199] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.851959] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.855147] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.861554] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.865186] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.870719] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.879283] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.888383] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Apply Kernel Variables... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... [ 13.071473] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.310487] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.652433] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.695310] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.786831] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.802230] EDAC sbridge: Ver: 1.1.2 [ 14.933444] Key type dns_resolver registered [ 15.239668] NFS: Registering the id_resolver key type [ 15.241447] Key type id_resolver registered [ 15.243776] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ 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 oleg137-client login: [ 70.583321] libcfs: loading out-of-tree module taints kernel. [ 70.757576] Key type ._llcrypt registered [ 70.759445] Key type .llcrypt registered [ 71.341924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 71.353363] alg: No test for adler32 (adler32-zlib) [ 72.829606] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [ 73.720293] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [ 75.463169] Key type lgssc registered [ 77.185768] Lustre: Echo OBD driver; http://www.lustre.org/ [ 124.674558] hrtimer: interrupt took 3681505 ns [ 235.717288] Lustre: Mounted lustre-client [ 240.989403] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 256.858804] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing check_logdir /tmp/testlogs/ [ 260.596557] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing yml_node [ 261.605102] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 23s idle [ 265.188752] Lustre: DEBUG MARKER: Client: 2.16.61.46 [ 267.817143] Lustre: DEBUG MARKER: MDS: 2.16.61.46 [ 270.321780] Lustre: DEBUG MARKER: OSS: 2.16.61.46 [ 272.054661] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 20:56:09 EST 2025 [ 287.654355] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 289.664858] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 291.333710] Lustre: DEBUG MARKER: === sanityn: start setup 20:56:28 (1763344588) === [ 292.108777] Lustre: Mounted lustre-client [ 295.862399] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing check_config_client /mnt/lustre [ 314.289679] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 323.296376] Lustre: DEBUG MARKER: === sanityn: finish setup 20:57:00 (1763344620) === [ 325.152828] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 20:57:02 (1763344622) [ 331.359686] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 20:57:09 (1763344629) [ 337.022309] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 20:57:14 (1763344634) [ 342.689600] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 20:57:20 (1763344640) [ 347.872301] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 20:57:25 (1763344645) [ 353.182867] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 20:57:30 (1763344650) [ 359.132928] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 20:57:36 (1763344656) [ 365.865192] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 20:57:43 (1763344663) [ 373.083414] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 20:57:50 (1763344670) [ 380.439513] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 20:57:57 (1763344677) [ 388.879935] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 20:58:06 (1763344686) [ 394.722192] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 21s idle [ 394.724853] Lustre: Skipped 1 previous similar message [ 397.219827] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 20:58:14 (1763344694) [ 404.447387] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 20:58:21 (1763344701) [ 412.006478] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 20:58:29 (1763344709) [ 415.226377] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 24s idle [ 419.000343] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 20:58:36 (1763344716) [ 425.388646] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 20:58:42 (1763344722) [ 430.559317] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 21s idle [ 432.646431] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 20:58:49 (1763344729) [ 438.935748] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 20:58:56 (1763344736) [ 446.635691] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 20:59:04 (1763344744) [ 452.517839] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 20:59:10 (1763344750) [ 458.566026] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 20:59:16 (1763344756) [ 459.427285] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 600.710769] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 21:01:38 (1763344898) [ 607.126647] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 21:01:44 (1763344904) [ 612.027113] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 21:01:49 (1763344909) [ 616.781763] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 21:01:54 (1763344914) [ 622.898525] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 21:02:00 (1763344920) [ 627.836929] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 21:02:05 (1763344925) [ 629.504832] Lustre: DEBUG MARKER: chmod [ 634.721995] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 21:02:12 (1763344932) [ 1347.039774] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 20s idle [ 1352.159752] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 23s idle [ 1352.177797] Lustre: Skipped 1 previous similar message [ 1357.280670] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 21s idle [ 1359.548840] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 21:14:17 (1763345657) [ 1500.639383] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 23s idle [ 1568.249541] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 21:17:45 (1763345865) [ 1676.801411] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 21:19:34 (1763345974) [ 1821.608118] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 21:21:58 (1763346118) [ 1864.162857] Lustre: lustre-OST0001-osc-ffff88df10a31000: disconnect after 23s idle [ 1865.252047] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 21:22:42 (1763346162) [ 1872.720631] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 21:22:50 (1763346170) [ 1874.055362] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.155351] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.279117] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.413262] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.576535] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.658763] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.776670] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.898902] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1874.990910] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.098788] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.202115] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.249873] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.335394] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.410319] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.674328] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.761750] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.872881] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1875.958361] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.028087] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.115660] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.204392] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.284696] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.376738] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.481969] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.529682] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.619782] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.730136] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.835059] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.894061] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1876.991832] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.047509] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.184814] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.321284] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.413732] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.523361] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.619191] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.720794] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.806300] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.908535] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1877.978218] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.056581] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.127994] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.199324] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.319627] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.403357] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.491185] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.583246] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.700890] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.809170] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1878.924617] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.013759] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.181043] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.282921] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.365294] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.466862] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.571449] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.694168] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.784905] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1879.890683] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.013370] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.124427] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.221872] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.323693] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.417112] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.517677] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.625428] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.722889] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.838215] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1880.915700] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.041504] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.122713] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.232939] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.349944] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.470435] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.596225] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.721866] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1881.882970] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.007001] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.099632] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.186959] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.307181] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.416451] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.527834] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.613395] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.687124] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.784625] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.885343] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1882.971686] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.096266] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.221870] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.285268] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.443399] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.542377] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.683645] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.845692] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1883.972971] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.087125] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.205275] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.286838] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.434929] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.500185] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.565688] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.670346] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.750933] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.859651] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.926991] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1884.985031] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.099423] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.185312] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.233892] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.274755] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.324420] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.379644] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.474751] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.565844] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.618471] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.718674] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.811633] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.894038] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1885.996940] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.069834] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.148927] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.225434] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.294021] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.390618] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.482955] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.577691] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.645908] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.690458] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.769370] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.885191] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1886.989905] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.075911] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.155824] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.238750] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.349132] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.444264] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.499372] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.585442] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.664887] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.768732] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.863906] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1887.993605] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.092576] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.167587] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.244109] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.296286] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.379655] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.473352] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.608190] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.647539] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.698882] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.831907] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1888.957829] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.059801] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.196694] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.265277] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.356381] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.457061] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.557591] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.645704] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.734674] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.759175] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 24s idle [ 1889.813477] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.853730] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.940085] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1889.993604] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.083404] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.158944] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.229976] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.299710] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.425371] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.534090] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.616085] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.701260] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.794363] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1890.912676] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.005892] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.089828] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.191496] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.266988] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.334938] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.413977] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.506865] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.576121] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.664850] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.731946] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.817508] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.897875] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1891.993266] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.060820] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.126296] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.190275] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.263824] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.312406] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.382249] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.433904] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.499301] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.586302] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.650839] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.758060] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.888052] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1892.974087] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1893.022272] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1893.090199] rw_seq_cst_vs_d (32263): drop_caches: 3 [ 1902.099880] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 21:23:19 (1763346199) [ 1902.646936] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1902.723751] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1902.888013] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1902.918133] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.042554] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.164247] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.310505] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.393099] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.551865] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.689861] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.747130] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1903.920804] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.070390] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.224358] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.259461] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.368632] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.419426] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.557277] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.687703] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.853634] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1904.996101] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1905.144812] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1905.244817] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1905.417763] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1905.627284] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1905.893736] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1906.380435] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1906.576298] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1906.680784] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1906.836231] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.004606] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.120413] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.240621] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.389104] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.447405] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.588513] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.645673] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.903838] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1907.988534] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.092685] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.245756] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.453813] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.579281] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.638758] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.705662] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.826398] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1908.953910] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.153501] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.229605] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.298717] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.367530] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.495754] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.643558] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.785787] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1909.861390] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.045029] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.208744] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.317661] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.366861] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.410069] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.563117] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.674886] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.794054] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1910.964707] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.071826] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.103174] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.260765] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.348266] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.462931] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.532368] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.679639] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.755479] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.831399] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1911.927726] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.033709] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.116631] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.202620] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.392545] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.551567] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.671736] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.852754] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.912424] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1912.964291] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.144931] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.187380] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.308684] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.414279] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.554428] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.636470] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.739538] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1913.853612] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.109841] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.225198] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.430535] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.549897] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.662210] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.757347] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1914.850449] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.184920] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.350185] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.360464] Lustre: lustre-OST0001-osc-ffff88df10a31000: disconnect after 23s idle [ 1915.362852] Lustre: Skipped 1 previous similar message [ 1915.470781] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.630545] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.692406] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.786097] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.922208] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1915.971307] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.061660] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.246565] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.333485] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.407373] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.475526] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.513424] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.558203] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.602774] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.761977] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.834811] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1916.904347] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1917.139259] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1917.218295] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1917.438347] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1917.566693] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1917.712060] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.031528] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.269329] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.347445] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.431487] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.541502] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.655438] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1918.788476] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.047839] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.182717] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.302814] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.334442] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.528391] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.656439] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.835406] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.900188] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1919.956473] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.022344] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.072500] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.130770] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.240078] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.406450] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.520936] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.642309] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.706084] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.770292] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1920.931684] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.017817] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.166056] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.354233] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.513892] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.777955] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.817306] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1921.983970] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1922.153480] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1922.323705] rw_seq_cst_vs_d (32850): drop_caches: 3 [ 1930.200590] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 21:23:47 (1763346227) [ 1937.972398] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 21:23:55 (1763346235) [ 1945.029666] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 21:24:02 (1763346242) [ 1971.377272] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 21:24:28 (1763346268) [ 1971.734863] bash (35335): drop_caches: 3 [ 1975.026951] bash (35335): drop_caches: 3 [ 1978.266306] bash (35335): drop_caches: 3 [ 1981.471239] bash (35335): drop_caches: 3 [ 1984.675321] bash (35335): drop_caches: 3 [ 1987.850217] bash (35335): drop_caches: 3 [ 1991.006613] bash (35335): drop_caches: 3 [ 1994.133838] bash (35335): drop_caches: 3 [ 1997.673299] bash (35335): drop_caches: 3 [ 2001.104394] bash (35335): drop_caches: 3 [ 2004.189975] bash (35335): drop_caches: 3 [ 2008.110661] bash (35335): drop_caches: 3 [ 2011.202767] bash (35335): drop_caches: 3 [ 2014.438544] bash (35335): drop_caches: 3 [ 2019.858493] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 21:25:16 (1763346316) [ 2031.537086] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 21:25:28 (1763346328) [ 2043.359776] Lustre: lustre-OST0001-osc-ffff88df10a31000: disconnect after 23s idle [ 2043.372160] Lustre: Skipped 1 previous similar message [ 2070.678947] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 21:26:08 (1763346368) [ 2078.833390] Lustre: DEBUG MARKER: loop 5 [ 2082.976426] Lustre: DEBUG MARKER: loop 10 [ 2087.966719] Lustre: DEBUG MARKER: loop 15 [ 2093.385676] Lustre: DEBUG MARKER: loop 20 [ 2102.456710] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 21:26:39 (1763346399) [ 2109.805662] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 21:26:47 (1763346407) [ 2116.431141] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 21:26:54 (1763346414) [ 2125.288984] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 22s idle [ 2125.303598] Lustre: Skipped 2 previous similar messages [ 2184.778609] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 21:28:02 (1763346482) [ 2191.623950] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 21:28:09 (1763346489) [ 2197.415817] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 21:28:15 (1763346495) [ 2203.935475] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 21:28:21 (1763346501) [ 2210.610702] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 21:28:28 (1763346508) [ 2216.917651] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 21:28:34 (1763346514) [ 2225.378632] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 21:28:42 (1763346522) [ 2231.725784] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2233.257541] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 21:28:50 (1763346530) [ 2241.528726] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 21:28:59 (1763346539) [ 2242.250186] Lustre: *** cfs_fail_loc=314, val=0*** [ 2243.295652] Lustre: *** cfs_fail_loc=314, val=0*** [ 2243.302789] Lustre: Skipped 2 previous similar messages [ 2250.390545] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 21:29:07 (1763346547) [ 2260.574616] Lustre: *** cfs_fail_loc=314, val=0*** [ 2260.647433] LustreError: lustre-OST0000-osc-ffff88df10a31000: operation ldlm_enqueue to node 192.168.201.137@tcp failed: rc = -107 [ 2260.664225] Lustre: lustre-OST0000-osc-ffff88df10a31000: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2260.694145] LustreError: lustre-OST0000-osc-ffff88df10a31000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2260.712268] Lustre: 2419:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.201.137@tcp:/lustre/fid: [0x240000403:0x1:0x0]// may get corrupted (rc -108) [ 2260.727199] LustreError: 46253:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff88df10a31000: namespace resource [0x280000401:0x39:0x0].0x0 (ffff88df19dc5b00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2260.756515] Lustre: lustre-OST0000-osc-ffff88df10a31000: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 2263.519980] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 21s idle [ 2263.523292] Lustre: Skipped 3 previous similar messages [ 2266.944755] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 21:29:24 (1763346564) [ 2267.329873] LustreError: 46843:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2270.367196] LustreError: 46843:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2276.092638] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 21:29:33 (1763346573) [ 2282.753340] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 21:29:39 (1763346579) [ 2290.849751] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2292.400461] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 21:29:50 (1763346590) [ 2294.255936] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2296.219448] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 21:29:53 (1763346593) [ 2299.374086] Lustre: lustre-MDT0000-mdc-ffff88df2030f800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2309.618241] LustreError: MGC192.168.201.137@tcp: Connection to MGS (at 192.168.201.137@tcp) was lost; in progress operations using this service will fail [ 2309.640997] Lustre: Evicted from MGS (at 192.168.201.137@tcp) after server handle changed from 0x72528bf236c730f6 to 0x72528bf236d29fc8 [ 2309.652245] Lustre: MGC192.168.201.137@tcp: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 2313.185577] Lustre: lustre-MDT0000-mdc-ffff88df2030f800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 2339.062595] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 21:30:36 (1763346636) [ 2373.867972] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 21:31:11 (1763346671) [ 2393.326776] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 21:31:30 (1763346690) [ 2446.812231] Lustre: lustre-OST0000-osc-ffff88df2030f800: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2446.834038] Lustre: Skipped 1 previous similar message [ 2446.859690] LustreError: lustre-OST0000-osc-ffff88df2030f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2446.877457] LustreError: lustre-OST0000-osc-ffff88df10a31000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2446.879293] Lustre: lustre-OST0000-osc-ffff88df2030f800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 2446.894130] Lustre: Skipped 2 previous similar messages [ 2462.175597] Lustre: lustre-OST0001-osc-ffff88df2030f800: Connection to lustre-OST0001 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2462.192676] Lustre: Skipped 1 previous similar message [ 2462.199914] LustreError: lustre-OST0001-osc-ffff88df2030f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2462.206212] Lustre: lustre-OST0001-osc-ffff88df2030f800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 2477.777164] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid 50 [ 2479.325581] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid in IDLE state after 0 sec [ 2482.177637] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid 50 [ 2483.376397] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid in FULL state after 0 sec [ 2487.179654] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid 50 [ 2488.386210] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid in IDLE state after 0 sec [ 2491.328508] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid 50 [ 2492.608853] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid in FULL state after 0 sec [ 2500.411187] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid 50 [ 2501.705432] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff88df10a31000.ost_server_uuid in IDLE state after 0 sec [ 2504.768926] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid 50 [ 2506.152687] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff88df10a31000.ost_server_uuid in FULL state after 0 sec [ 2507.530917] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 21:33:25 (1763346805) [ 2510.221466] Lustre: DEBUG MARKER: Race attempt 0 [ 2512.983023] Lustre: DEBUG MARKER: Wait for 57309 57340 for 60 sec... [ 2579.443890] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 21:34:36 (1763346876) [ 2586.808778] Lustre: DEBUG MARKER: start test - cycle (0) [ 2606.892482] Lustre: DEBUG MARKER: start test - cycle (1) [ 2628.011981] Lustre: DEBUG MARKER: start test - cycle (2) [ 2649.041640] Lustre: DEBUG MARKER: start test - cycle (3) [ 2669.847942] Lustre: DEBUG MARKER: start test - cycle (4) [ 2676.191635] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 21s idle [ 2676.195809] Lustre: Skipped 5 previous similar messages [ 2688.981491] Lustre: DEBUG MARKER: start test - cycle (5) [ 2711.280264] Lustre: DEBUG MARKER: start test - cycle (6) [ 2730.390977] Lustre: DEBUG MARKER: start test - cycle (7) [ 2751.776453] Lustre: DEBUG MARKER: start test - cycle (8) [ 2772.600792] Lustre: DEBUG MARKER: start test - cycle (9) [ 2799.274692] Lustre: DEBUG MARKER: start test - cycle (10) [ 2828.497758] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 21:38:45 (1763347125) [ 2909.639349] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 21:40:06 (1763347206) [ 2917.467404] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 21:40:14 (1763347214) [ 2926.171835] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 21:40:23 (1763347223) [ 2935.972697] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 21:40:32 (1763347232) [ 2936.479708] Lustre: *** cfs_fail_loc=411, val=0*** [ 2943.575226] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2945.712152] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 21:40:42 (1763347242) [ 2963.837366] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 21:41:01 (1763347261) [ 2979.400979] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 21:41:16 (1763347276) [ 2995.754822] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 21:41:32 (1763347292) [ 3011.174308] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 21:41:48 (1763347308) [ 3023.714509] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 21:42:00 (1763347320) [ 3037.064781] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 21:42:14 (1763347334) [ 3053.158830] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 21:42:30 (1763347350) [ 3067.168818] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 21:42:44 (1763347364) [ 3081.902118] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 21:42:58 (1763347378) [ 3097.357104] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 21:43:14 (1763347394) [ 3110.918485] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 21:43:28 (1763347408) [ 3126.077894] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 21:43:43 (1763347423) [ 3746.273258] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 25s idle [ 3746.294358] Lustre: Skipped 17 previous similar messages [ 4207.766180] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 22:01:45 (1763348505) [ 4220.945671] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 22:01:58 (1763348518) [ 4234.007798] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 22:02:11 (1763348531) [ 4247.676922] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 22:02:25 (1763348545) [ 4261.685465] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 22:02:38 (1763348558) [ 4274.979690] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 22:02:52 (1763348572) [ 4286.770274] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 22:03:04 (1763348584) [ 4298.333874] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 22:03:16 (1763348596) [ 4311.274388] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 22:03:28 (1763348608) [ 4411.188859] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 22:05:08 (1763348708) [ 4422.676917] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 22:05:20 (1763348720) [ 4434.011636] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 22:05:31 (1763348731) [ 4444.148947] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 22:05:41 (1763348741) [ 4454.122355] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 22:05:51 (1763348751) [ 4465.364388] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 22:06:02 (1763348762) [ 4477.780418] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 22:06:15 (1763348775) [ 4489.264375] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 22:06:26 (1763348786) [ 4500.584806] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 22:06:37 (1763348797) [ 4509.151926] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 23s idle [ 4509.160794] Lustre: Skipped 4 previous similar messages [ 4612.409985] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 22:08:30 (1763348910) [ 5111.264232] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 20s idle [ 5111.266686] Lustre: Skipped 7 previous similar messages [ 5702.113675] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 22:26:39 (1763349999) [ 5714.377387] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 22:26:52 (1763350012) [ 5715.424590] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 22s idle [ 5715.435396] Lustre: Skipped 3 previous similar messages [ 5727.537550] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 22:27:05 (1763350025) [ 5741.155953] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 22:27:18 (1763350038) [ 5752.792969] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 22:27:30 (1763350050) [ 5764.701128] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 22:27:42 (1763350062) [ 5776.224973] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 22:27:53 (1763350073) [ 5787.513952] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 22:28:05 (1763350085) [ 5798.501889] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 22:28:16 (1763350096) [ 5810.198253] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 22:28:27 (1763350107) [ 5973.565043] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 22:31:11 (1763350271) [ 5985.543468] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 22:31:23 (1763350283) [ 5997.274474] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 22:31:34 (1763350294) [ 6011.270761] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 22:31:48 (1763350308) [ 6024.001859] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 22:32:01 (1763350321) [ 6034.896991] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 22:32:12 (1763350332) [ 6045.417717] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 22:32:23 (1763350343) [ 6055.854705] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 22:32:33 (1763350353) [ 6066.748394] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 22:32:44 (1763350364) [ 7253.112556] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 22:52:30 (1763351550) [ 7265.027695] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 22:52:42 (1763351562) [ 7266.787354] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 20s idle [ 7266.800288] Lustre: Skipped 3 previous similar messages [ 7278.223915] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 22:52:55 (1763351575) [ 7290.741813] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 22:53:08 (1763351588) [ 7305.234867] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 22:53:22 (1763351602) [ 7319.087392] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 22:53:36 (1763351616) [ 7330.863265] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 22:53:48 (1763351628) [ 7342.434613] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 22:53:59 (1763351639) [ 7343.591913] Lustre: lustre-OST0000-osc-ffff88df2030f800: disconnect after 22s idle [ 7343.606964] Lustre: Skipped 3 previous similar messages [ 7354.715850] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 22:54:12 (1763351652) [ 7366.599794] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 22:54:24 (1763351664) [ 7378.303437] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 22:54:35 (1763351675) [ 7390.325722] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 22:54:47 (1763351687) [ 7401.766577] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 22:54:59 (1763351699) [ 7412.583131] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 22:55:10 (1763351710) [ 7424.521092] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 22:55:21 (1763351721) [ 7436.121642] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 22:55:33 (1763351733) [ 7449.313450] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 22:55:46 (1763351746) [ 7449.525829] LustreError: 6173:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7451.607300] LustreError: 6173:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7459.606104] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 22:55:57 (1763351757) [ 7466.864407] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 22:56:04 (1763351764) [ 7467.136395] LustreError: 237874:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7471.201257] LustreError: 237874:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7471.223052] LustreError: 237874:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7475.298252] LustreError: 237874:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7475.365314] LustreError: 237881:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7479.463278] LustreError: 237881:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7484.938175] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 22:56:22 (1763351782) [ 7495.483451] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 22:56:33 (1763351793) [ 7501.855451] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 22:56:39 (1763351799) [ 7502.306059] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 23s idle [ 7502.316255] Lustre: Skipped 4 previous similar messages [ 7509.433459] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 22:56:47 (1763351807) [ 7537.984493] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 22:57:15 (1763351835) [ 7548.177555] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 22:57:26 (1763351846) [ 7558.797302] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 22:57:36 (1763351856) [ 7575.469181] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 22:57:53 (1763351873) [ 7588.828399] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 22:58:06 (1763351886) [ 7605.364993] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 22:58:23 (1763351903) [ 7621.626693] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 22:58:39 (1763351919) [ 7639.597467] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 22:58:57 (1763351937) [ 7651.206328] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 22:59:09 (1763351949) [ 7696.071349] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 22:59:54 (1763351994) [ 7700.432330] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7705.669715] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 23:00:03 (1763352003) [ 7710.466605] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 23:00:08 (1763352008) [ 7714.862180] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 23:00:12 (1763352012) [ 7719.112987] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 23:00:16 (1763352016) [ 7750.506879] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 23:00:48 (1763352048) [ 7779.499953] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 23:01:17 (1763352077) [ 7783.514331] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 23:01:21 (1763352081) [ 7787.283968] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 23:01:25 (1763352085) [ 7790.508043] LustreError: lustre-MDT0000-mdc-ffff88df10a31000: operation ldlm_enqueue to node 192.168.201.137@tcp failed: rc = -35 [ 7794.872993] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 23:01:32 (1763352092) [ 7795.288940] LustreError: 2420:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7797.375181] LustreError: 2420:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7803.812371] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 23:01:41 (1763352101) [ 7814.623317] Lustre: lustre-OST0000-osc-ffff88df10a31000: disconnect after 20s idle [ 7814.626641] Lustre: Skipped 1 previous similar message [ 7908.742655] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 23:03:26 (1763352206) [ 7914.572259] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 23:03:32 (1763352212) [ 7922.315411] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 23:03:40 (1763352220) [ 7931.736564] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 23:03:49 (1763352229) [ 7942.486659] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 23:04:00 (1763352240) [ 7957.190586] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 23:04:15 (1763352255) [ 7972.729355] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 23:04:30 (1763352270) [ 7979.037906] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 23:04:36 (1763352276) [ 7985.566438] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 23:04:43 (1763352283) [ 7998.944203] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 23:04:56 (1763352296) [ 8046.567591] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 23:05:44 (1763352344) [ 8166.163566] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 23:07:43 (1763352463) [ 8285.875142] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 23:09:43 (1763352583) [ 8449.504034] Lustre: lustre-OST0001-osc-ffff88df2030f800: disconnect after 22s idle [ 8449.508187] Lustre: Skipped 12 previous similar messages [ 8580.217798] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 23:14:38 (1763352878) [ 8613.841782] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 23:15:11 (1763352911) [ 8644.507516] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 23:15:42 (1763352942) [ 8670.296478] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 23:16:08 (1763352968) [ 8730.503767] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 23:17:08 (1763353028) [ 8785.598269] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 23:18:03 (1763353083) [ 8880.239506] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 23:19:38 (1763353178) [ 8881.900492] LustreError: 283664:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df2030f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8881.906737] LustreError: 283664:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8881.935215] Lustre: Unmounted lustre-client [ 8882.878512] LustreError: 283677:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df10a31000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8882.885746] LustreError: 283677:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8882.889184] LustreError: 283677:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8882.891714] LustreError: 283677:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8882.936502] Lustre: Unmounted lustre-client [ 8961.532433] Lustre: Mounted lustre-client [ 8963.348736] Lustre: Mounted lustre-client [ 8964.870840] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9032.530724] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 23:22:10 (1763353330) [ 9042.142560] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 23:22:20 (1763353340) [ 9046.737772] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 23:22:24 (1763353344) [ 9098.815260] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 23:23:16 (1763353396) [ 9152.991161] Lustre: lustre-OST0001-osc-ffff88df08471800: disconnect after 24s idle [ 9152.994641] Lustre: Skipped 12 previous similar messages [ 9154.785483] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 23:24:12 (1763353452) [ 9159.995856] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 23:24:18 (1763353458) [ 9211.319566] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 23:25:09 (1763353509) [ 9227.625298] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 23:25:25 (1763353525) [ 9271.659723] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 23:26:09 (1763353569) [ 9275.528526] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 23:26:13 (1763353573) [ 9289.109229] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 23:26:27 (1763353587) [ 9294.129806] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 23:26:32 (1763353592) [ 9294.807886] LustreError: 303836:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x34e:0x0]: rc = -5 [ 9294.813657] LustreError: 303836:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9396.157157] LustreError: lustre-MDT0000-mdc-ffff88df08471800: operation mds_getattr_lock to node 192.168.201.137@tcp failed: rc = -107 [ 9396.162546] Lustre: lustre-MDT0000-mdc-ffff88df08471800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9396.170360] LustreError: lustre-MDT0000-mdc-ffff88df08471800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 9396.179092] LustreError: 303864:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff88df08471800: inode [0x2000013a1:0x344:0x0] mdc close failed: rc = -108 [ 9396.188216] Lustre: lustre-MDT0000-mdc-ffff88df08471800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [ 9399.497101] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 23:28:17 (1763353697) [ 9403.050468] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 23:28:21 (1763353701) [ 9494.284230] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 23:29:52 (1763353792) [ 9494.985699] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9495.714815] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 23:29:53 (1763353793) [ 9568.580516] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 23:31:06 (1763353866) [ 9571.156257] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 23:31:09 (1763353869) [ 9693.569604] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 23:33:11 (1763353991) [ 9701.542244] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 23:33:19 (1763353999) [ 9705.684375] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 23:33:23 (1763354003) [ 9888.613107] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 23:36:26 (1763354186) [ 9915.871244] Lustre: lustre-OST0001-osc-ffff88df02c3a000: disconnect after 20s idle [ 9915.874163] Lustre: Skipped 3 previous similar messages [10071.610875] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 23:39:29 (1763354369) [10074.363687] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 23:39:32 (1763354372) [10084.241766] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 23:39:42 (1763354382) [10084.360115] Lustre: DEBUG MARKER: write [10084.397295] LustreError: 285388:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10086.390584] Lustre: DEBUG MARKER: kill 351421 [10086.394512] LustreError: 351421:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10089.495234] LustreError: 285388:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10092.431136] LustreError: 351421:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10095.164168] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 23:39:53 (1763354393) [10097.402318] LustreError: 352034:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10107.495112] LustreError: 352034:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [10110.148779] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 23:40:08 (1763354408) [10110.249278] LustreError: 352624:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10112.335187] LustreError: 352624:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [10118.498816] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 23:40:16 (1763354416) [10119.147268] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10119.810763] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 23:40:18 (1763354418) [10122.725686] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 23:40:20 (1763354420) [10125.553633] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 23:40:23 (1763354423) [10128.287736] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 23:40:26 (1763354426) [10131.115593] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 23:40:29 (1763354429) [10133.708261] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 23:40:31 (1763354431) [10136.434189] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 23:40:34 (1763354434) [10140.073395] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 23:40:38 (1763354438) [10143.388591] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 23:40:41 (1763354441) [10144.089319] Lustre: *** cfs_fail_loc=415, val=0*** [10151.399562] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 23:40:49 (1763354449) [10172.232707] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 23:41:10 (1763354470) [10172.412289] LustreError: 285388:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [10172.415929] LustreError: 285388:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [10177.415376] LustreError: 285388:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10187.623460] LustreError: 285388:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10187.627352] LustreError: 285388:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 6 previous similar messages [10195.414603] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 23:41:33 (1763354493) [10198.162614] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 23:41:36 (1763354496) [10200.838417] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 23:41:39 (1763354499) [10203.475071] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 23:41:41 (1763354501) [10208.448589] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 23:41:46 (1763354506) [10217.244463] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 23:41:55 (1763354515) [10217.449627] LustreError: 363357:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [10217.453394] LustreError: 363357:0:(osc_request.c:2943:osc_build_rpc()) Skipped 6 previous similar messages [10221.511162] LustreError: 363357:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 awake [10221.514141] LustreError: 363357:0:(osc_request.c:2943:osc_build_rpc()) Skipped 2 previous similar messages [10224.160415] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 23:42:02 (1763354522) [10225.482217] LustreError: 364066:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df08471800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10225.486000] LustreError: 364066:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10225.491923] LustreError: 364066:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10225.494960] LustreError: 364066:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10225.518360] Lustre: Unmounted lustre-client [10226.039593] LustreError: 364086:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c3a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10226.047144] LustreError: 364086:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10226.058565] LustreError: 364086:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10226.061296] LustreError: 364086:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10226.090073] Lustre: Unmounted lustre-client [10226.700535] Lustre: DEBUG MARKER: Iteration 1 [10226.821578] LustreError: 364250:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10226.821889] LustreError: 364249:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10226.829077] LustreError: 364250:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10226.899959] Lustre: Mounted lustre-client [10227.419791] LustreError: 364363:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df05496800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10227.425355] LustreError: 364363:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [10227.429515] LustreError: 364365:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10227.432677] LustreError: 364365:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10227.468772] Lustre: Unmounted lustre-client [10228.550620] Key type lgssc unregistered [10228.687531] LNet: 364606:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10228.689625] LNetError: 364606:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10228.697045] LNet: Removed LNI 192.168.201.37@tcp [10229.036141] Key type .llcrypt unregistered [10229.037679] Key type ._llcrypt unregistered [10229.317678] Key type ._llcrypt registered [10229.319039] Key type .llcrypt registered [10229.600311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10229.607720] alg: No test for adler32 (adler32-zlib) [10230.613383] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10230.916226] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10232.543279] Key type lgssc registered [10233.171614] Lustre: Echo OBD driver; http://www.lustre.org/ [10238.205513] Lustre: DEBUG MARKER: Iteration 2 [10238.344746] LustreError: 365436:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10238.345464] LustreError: 365435:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10238.350942] LustreError: 365436:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10239.423061] Lustre: Mounted lustre-client [10239.425150] Lustre: Skipped 1 previous similar message [10239.904696] LustreError: 365548:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0668e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10239.909634] LustreError: 365548:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10239.911382] LustreError: 365547:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10239.918289] LustreError: 365547:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10239.962459] Lustre: Unmounted lustre-client [10241.055265] Key type lgssc unregistered [10241.188558] LNet: 365790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10241.191989] LNetError: 365790:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10241.202958] LNet: Removed LNI 192.168.201.37@tcp [10241.484574] Key type .llcrypt unregistered [10241.486278] Key type ._llcrypt unregistered [10241.752895] Key type ._llcrypt registered [10241.756870] Key type .llcrypt registered [10241.966672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10241.973336] alg: No test for adler32 (adler32-zlib) [10242.857667] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10242.959203] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10244.559221] Key type lgssc registered [10245.056644] Lustre: Echo OBD driver; http://www.lustre.org/ [10249.409794] Lustre: DEBUG MARKER: Iteration 3 [10249.528296] LustreError: 366620:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10249.528336] LustreError: 366621:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10249.534836] LustreError: 366620:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10250.606234] Lustre: Mounted lustre-client [10251.105492] LustreError: 366729:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df057c4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10251.112686] LustreError: 366729:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10251.198527] Lustre: Unmounted lustre-client [10252.286682] Key type lgssc unregistered [10252.420373] LNet: 366975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10252.423678] LNetError: 366975:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10252.433964] LNet: Removed LNI 192.168.201.37@tcp [10252.711196] Key type .llcrypt unregistered [10252.712644] Key type ._llcrypt unregistered [10253.033778] Key type ._llcrypt registered [10253.041049] Key type .llcrypt registered [10253.221451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10253.228664] alg: No test for adler32 (adler32-zlib) [10254.100133] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10254.193804] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10255.783239] Key type lgssc registered [10256.253321] Lustre: Echo OBD driver; http://www.lustre.org/ [10260.494267] Lustre: DEBUG MARKER: Iteration 4 [10260.632461] LustreError: 367804:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10260.632511] LustreError: 367805:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10260.640533] LustreError: 367804:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10261.739881] Lustre: Mounted lustre-client [10261.741791] Lustre: Skipped 1 previous similar message [10262.204170] LustreError: 367918:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df2040e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10262.208239] LustreError: 367918:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10262.213502] LustreError: 367917:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10262.216727] LustreError: 367917:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10262.247558] Lustre: Unmounted lustre-client [10263.364986] Key type lgssc unregistered [10263.492473] LNet: 368160:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10263.498095] LNetError: 368160:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10263.508216] LNet: Removed LNI 192.168.201.37@tcp [10263.816304] Key type .llcrypt unregistered [10263.818040] Key type ._llcrypt unregistered [10264.130565] Key type ._llcrypt registered [10264.131838] Key type .llcrypt registered [10264.332250] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10264.338764] alg: No test for adler32 (adler32-zlib) [10265.232714] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10265.339846] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10266.935174] Key type lgssc registered [10267.427250] Lustre: Echo OBD driver; http://www.lustre.org/ [10271.880354] Lustre: DEBUG MARKER: Iteration 5 [10272.029976] LustreError: 368989:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10272.030900] LustreError: 368990:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10272.035653] LustreError: 368989:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10273.146391] Lustre: Mounted lustre-client [10273.148330] Lustre: Skipped 1 previous similar message [10273.941833] LustreError: 369096:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0a69f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10273.962896] LustreError: 369096:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10274.002079] Lustre: Unmounted lustre-client [10275.307617] Key type lgssc unregistered [10275.454169] LNet: 369346:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10275.457848] LNetError: 369346:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10275.471832] LNet: Removed LNI 192.168.201.37@tcp [10275.769320] Key type .llcrypt unregistered [10275.771028] Key type ._llcrypt unregistered [10276.036155] Key type ._llcrypt registered [10276.037895] Key type .llcrypt registered [10276.223721] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10276.236989] alg: No test for adler32 (adler32-zlib) [10277.126607] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10277.235379] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10278.839183] Key type lgssc registered [10279.380538] Lustre: Echo OBD driver; http://www.lustre.org/ [10283.920568] Lustre: DEBUG MARKER: Iteration 6 [10284.067574] LustreError: 370174:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10284.068728] LustreError: 370175:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10284.074227] LustreError: 370174:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10285.162135] Lustre: Mounted lustre-client [10285.163803] Lustre: Skipped 1 previous similar message [10285.862060] LustreError: 370289:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0668f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10285.868790] LustreError: 370290:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10285.870221] LustreError: 370289:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10285.910441] Lustre: Unmounted lustre-client [10287.193118] Key type lgssc unregistered [10287.326934] LNet: 370531:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10287.330715] LNetError: 370531:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10287.340948] LNet: Removed LNI 192.168.201.37@tcp [10287.639172] Key type .llcrypt unregistered [10287.640891] Key type ._llcrypt unregistered [10287.959398] Key type ._llcrypt registered [10287.961294] Key type .llcrypt registered [10288.159711] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10288.164987] alg: No test for adler32 (adler32-zlib) [10289.043602] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10289.151525] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10290.751248] Key type lgssc registered [10291.241860] Lustre: Echo OBD driver; http://www.lustre.org/ [10295.476838] Lustre: DEBUG MARKER: Iteration 7 [10295.642716] LustreError: 371361:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10295.643231] LustreError: 371360:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10295.655773] LustreError: 371361:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10296.770884] Lustre: Mounted lustre-client [10297.554735] LustreError: 371473:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0a69e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10297.566390] LustreError: 371473:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10297.571318] LustreError: 371473:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10297.639546] Lustre: Unmounted lustre-client [10298.875559] Key type lgssc unregistered [10299.006710] LNet: 371715:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10299.010087] LNetError: 371715:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10299.020794] LNet: Removed LNI 192.168.201.37@tcp [10299.309161] Key type .llcrypt unregistered [10299.311568] Key type ._llcrypt unregistered [10299.623273] Key type ._llcrypt registered [10299.629456] Key type .llcrypt registered [10299.835365] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10299.843725] alg: No test for adler32 (adler32-zlib) [10300.750335] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10300.868873] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10302.479262] Key type lgssc registered [10303.048049] Lustre: Echo OBD driver; http://www.lustre.org/ [10307.973646] Lustre: DEBUG MARKER: Iteration 8 [10308.116300] LustreError: 372543:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10308.116341] LustreError: 372544:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10308.123302] LustreError: 372543:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10309.221701] Lustre: Mounted lustre-client [10309.223573] Lustre: Skipped 1 previous similar message [10309.939212] LustreError: 372659:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c3a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10309.950693] LustreError: 372659:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10309.957070] LustreError: 372659:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10310.008226] Lustre: Unmounted lustre-client [10310.009852] Lustre: Skipped 1 previous similar message [10311.263446] Key type lgssc unregistered [10311.394675] LNet: 372903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10311.402069] LNetError: 372903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10311.414046] LNet: Removed LNI 192.168.201.37@tcp [10311.772821] Key type .llcrypt unregistered [10311.777427] Key type ._llcrypt unregistered [10312.162692] Key type ._llcrypt registered [10312.167446] Key type .llcrypt registered [10312.359389] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10312.366839] alg: No test for adler32 (adler32-zlib) [10313.247594] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10313.347257] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10314.943247] Key type lgssc registered [10315.453609] Lustre: Echo OBD driver; http://www.lustre.org/ [10319.589474] Lustre: DEBUG MARKER: Iteration 9 [10319.706502] LustreError: 373730:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10319.706525] LustreError: 373732:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10319.713172] LustreError: 373730:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10320.784817] Lustre: Mounted lustre-client [10321.258767] LustreError: 373847:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df18b09000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10321.267776] LustreError: 373847:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10321.271197] LustreError: 373847:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10321.318404] Lustre: Unmounted lustre-client [10322.423774] Key type lgssc unregistered [10322.548357] LNet: 374091:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10322.552316] LNetError: 374091:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10322.562279] LNet: Removed LNI 192.168.201.37@tcp [10322.826131] Key type .llcrypt unregistered [10322.827868] Key type ._llcrypt unregistered [10323.096460] Key type ._llcrypt registered [10323.098334] Key type .llcrypt registered [10323.299888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10323.309754] alg: No test for adler32 (adler32-zlib) [10324.183382] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10324.277724] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10325.871170] Key type lgssc registered [10326.328614] Lustre: Echo OBD driver; http://www.lustre.org/ [10330.482595] Lustre: DEBUG MARKER: Iteration 10 [10330.601052] LustreError: 374932:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10330.602085] LustreError: 374931:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10330.607872] LustreError: 374932:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10331.687536] Lustre: Mounted lustre-client [10332.224171] LustreError: 375045:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df08c57800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10332.231791] LustreError: 375045:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10332.302986] Lustre: Unmounted lustre-client [10333.417343] Key type lgssc unregistered [10333.543192] LNet: 375287:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10333.546500] LNetError: 375287:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10333.554838] LNet: Removed LNI 192.168.201.37@tcp [10333.812129] Key type .llcrypt unregistered [10333.813500] Key type ._llcrypt unregistered [10334.081718] Key type ._llcrypt registered [10334.082936] Key type .llcrypt registered [10334.294666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10334.304099] alg: No test for adler32 (adler32-zlib) [10335.189770] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10335.290667] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10336.887186] Key type lgssc registered [10337.372604] Lustre: Echo OBD driver; http://www.lustre.org/ [10341.630144] Lustre: DEBUG MARKER: Iteration 11 [10341.743277] LustreError: 376116:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10341.743340] LustreError: 376117:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10341.750896] LustreError: 376116:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10342.840881] Lustre: Mounted lustre-client [10342.842445] Lustre: Skipped 1 previous similar message [10343.338444] LustreError: 376227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0463a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10343.341877] LustreError: 376229:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10343.343268] LustreError: 376227:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10343.380271] Lustre: Unmounted lustre-client [10344.495613] Key type lgssc unregistered [10344.621454] LNet: 376470:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10344.624729] LNetError: 376470:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10344.633818] LNet: Removed LNI 192.168.201.37@tcp [10344.902187] Key type .llcrypt unregistered [10344.903905] Key type ._llcrypt unregistered [10345.161515] Key type ._llcrypt registered [10345.167622] Key type .llcrypt registered [10345.404309] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10345.411279] alg: No test for adler32 (adler32-zlib) [10346.299416] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10346.408257] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10348.007537] Key type lgssc registered [10348.665823] Lustre: Echo OBD driver; http://www.lustre.org/ [10353.088771] Lustre: DEBUG MARKER: Iteration 12 [10353.193209] LustreError: 377298:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10353.193943] LustreError: 377300:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10353.198847] LustreError: 377298:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10354.258445] Lustre: Mounted lustre-client [10354.750184] LustreError: 377414:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c3a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10354.753449] LustreError: 377413:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10354.755376] LustreError: 377414:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10354.797272] Lustre: Unmounted lustre-client [10355.887029] Key type lgssc unregistered [10356.011499] LNet: 377657:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10356.014455] LNetError: 377657:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10356.022569] LNet: Removed LNI 192.168.201.37@tcp [10356.303165] Key type .llcrypt unregistered [10356.305411] Key type ._llcrypt unregistered [10356.586705] Key type ._llcrypt registered [10356.595186] Key type .llcrypt registered [10356.807425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10356.814632] alg: No test for adler32 (adler32-zlib) [10357.702508] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10357.806736] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10359.399178] Key type lgssc registered [10359.877424] Lustre: Echo OBD driver; http://www.lustre.org/ [10364.083370] Lustre: DEBUG MARKER: Iteration 13 [10364.206767] LustreError: 378486:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10364.207230] LustreError: 378487:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10364.212748] LustreError: 378486:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10365.291388] Lustre: Mounted lustre-client [10365.293023] Lustre: Skipped 1 previous similar message [10365.756882] LustreError: 378600:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df33b9c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10365.762490] LustreError: 378601:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10365.763082] LustreError: 378600:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10365.810068] Lustre: Unmounted lustre-client [10366.821160] Key type lgssc unregistered [10366.939623] LNet: 378843:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10366.943275] LNetError: 378843:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10366.953688] LNet: Removed LNI 192.168.201.37@tcp [10367.184177] Key type .llcrypt unregistered [10367.185786] Key type ._llcrypt unregistered [10367.485272] Key type ._llcrypt registered [10367.487653] Key type .llcrypt registered [10367.673674] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10367.680903] alg: No test for adler32 (adler32-zlib) [10368.559924] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10368.663141] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10370.263170] Key type lgssc registered [10370.695847] Lustre: Echo OBD driver; http://www.lustre.org/ [10374.778933] Lustre: DEBUG MARKER: Iteration 14 [10374.893838] LustreError: 379671:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10374.893915] LustreError: 379672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10374.901237] LustreError: 379671:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10375.964987] Lustre: Mounted lustre-client [10375.966582] Lustre: Skipped 1 previous similar message [10376.444057] LustreError: 379783:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df18347800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10376.448378] LustreError: 379784:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10376.449324] LustreError: 379783:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10376.493071] Lustre: Unmounted lustre-client [10377.525994] Key type lgssc unregistered [10377.651606] LNet: 380026:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10377.654534] LNetError: 380026:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10377.664583] LNet: Removed LNI 192.168.201.37@tcp [10377.908172] Key type .llcrypt unregistered [10377.910051] Key type ._llcrypt unregistered [10378.189022] Key type ._llcrypt registered [10378.198896] Key type .llcrypt registered [10378.394621] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10378.401515] alg: No test for adler32 (adler32-zlib) [10379.303525] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10379.409483] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10381.015168] Key type lgssc registered [10381.505333] Lustre: Echo OBD driver; http://www.lustre.org/ [10385.866687] Lustre: DEBUG MARKER: Iteration 15 [10385.977152] LustreError: 380854:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10385.977516] LustreError: 380855:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10385.983898] LustreError: 380854:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10387.052536] Lustre: Mounted lustre-client [10387.548920] LustreError: 380968:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df35fad000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10387.554527] LustreError: 380968:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10387.558274] LustreError: 380967:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10387.561604] LustreError: 380967:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10387.596616] Lustre: Unmounted lustre-client [10388.674544] Key type lgssc unregistered [10388.796406] LNet: 381209:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10388.799816] LNetError: 381209:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10388.807680] LNet: Removed LNI 192.168.201.37@tcp [10389.077160] Key type .llcrypt unregistered [10389.078693] Key type ._llcrypt unregistered [10389.359673] Key type ._llcrypt registered [10389.361482] Key type .llcrypt registered [10389.550320] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10389.556501] alg: No test for adler32 (adler32-zlib) [10390.432266] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10390.528637] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10392.127213] Key type lgssc registered [10392.574318] Lustre: Echo OBD driver; http://www.lustre.org/ [10396.580845] Lustre: DEBUG MARKER: Iteration 16 [10396.691628] LustreError: 382037:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10396.691679] LustreError: 382038:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10396.698380] LustreError: 382037:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10397.766090] Lustre: Mounted lustre-client [10398.262083] LustreError: 382151:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0668e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10398.269316] LustreError: 382151:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10398.331248] Lustre: Unmounted lustre-client [10399.383424] Key type lgssc unregistered [10399.510388] LNet: 382394:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10399.513546] LNetError: 382394:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10399.521775] LNet: Removed LNI 192.168.201.37@tcp [10399.797181] Key type .llcrypt unregistered [10399.798993] Key type ._llcrypt unregistered [10400.085163] Key type ._llcrypt registered [10400.086673] Key type .llcrypt registered [10400.283538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10400.289935] alg: No test for adler32 (adler32-zlib) [10401.151863] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10401.252071] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10402.839190] Key type lgssc registered [10403.305059] Lustre: Echo OBD driver; http://www.lustre.org/ [10407.457506] Lustre: DEBUG MARKER: Iteration 17 [10407.580301] LustreError: 383222:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10407.580354] LustreError: 383223:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10407.586780] LustreError: 383222:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10408.669821] Lustre: Mounted lustre-client [10408.671393] Lustre: Skipped 1 previous similar message [10409.152841] LustreError: 383338:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df08c53800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10409.159363] LustreError: 383338:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10409.162806] LustreError: 383338:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10409.189320] Lustre: Unmounted lustre-client [10410.296519] Key type lgssc unregistered [10410.422926] LNet: 383580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10410.426780] LNetError: 383580:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10410.436650] LNet: Removed LNI 192.168.201.37@tcp [10410.707170] Key type .llcrypt unregistered [10410.708890] Key type ._llcrypt unregistered [10410.960644] Key type ._llcrypt registered [10410.962195] Key type .llcrypt registered [10411.167704] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10411.176390] alg: No test for adler32 (adler32-zlib) [10412.065591] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10412.163525] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10413.751195] Key type lgssc registered [10414.203655] Lustre: Echo OBD driver; http://www.lustre.org/ [10418.279323] Lustre: DEBUG MARKER: Iteration 18 [10418.397853] LustreError: 384407:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10418.397903] LustreError: 384409:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10418.405181] LustreError: 384407:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10419.476558] Lustre: Mounted lustre-client [10419.478472] Lustre: Skipped 1 previous similar message [10419.957186] LustreError: 384522:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df03ce4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10419.961460] LustreError: 384522:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10419.967326] LustreError: 384523:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10419.970407] LustreError: 384523:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10420.011085] Lustre: Unmounted lustre-client [10421.051734] Key type lgssc unregistered [10421.185479] LNet: 384765:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10421.189358] LNetError: 384765:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10421.204462] LNet: Removed LNI 192.168.201.37@tcp [10421.476183] Key type .llcrypt unregistered [10421.478068] Key type ._llcrypt unregistered [10421.761995] Key type ._llcrypt registered [10421.763762] Key type .llcrypt registered [10421.998578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10422.005463] alg: No test for adler32 (adler32-zlib) [10422.884480] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10422.979610] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10424.575173] Key type lgssc registered [10425.023503] Lustre: Echo OBD driver; http://www.lustre.org/ [10429.204745] Lustre: DEBUG MARKER: Iteration 19 [10429.332887] LustreError: 385594:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10429.332937] LustreError: 385593:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10429.342610] LustreError: 385594:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10430.421372] Lustre: Mounted lustre-client [10430.909058] LustreError: 385707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df2fa04800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10430.913559] LustreError: 385708:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10430.915122] LustreError: 385707:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10430.959218] Lustre: Unmounted lustre-client [10432.080917] Key type lgssc unregistered [10432.205660] LNet: 385948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10432.209282] LNetError: 385948:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10432.220332] LNet: Removed LNI 192.168.201.37@tcp [10432.512152] Key type .llcrypt unregistered [10432.513860] Key type ._llcrypt unregistered [10432.777501] Key type ._llcrypt registered [10432.778688] Key type .llcrypt registered [10433.014059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10433.020131] alg: No test for adler32 (adler32-zlib) [10433.901349] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10434.002382] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10435.599185] Key type lgssc registered [10436.064263] Lustre: Echo OBD driver; http://www.lustre.org/ [10440.339527] Lustre: DEBUG MARKER: Iteration 20 [10440.455738] LustreError: 386778:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10440.457341] LustreError: 386779:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10440.462626] LustreError: 386778:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10441.544559] Lustre: Mounted lustre-client [10442.113552] LustreError: 386890:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df104cb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10442.123861] LustreError: 386890:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10442.128496] LustreError: 386890:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10442.176766] Lustre: Unmounted lustre-client [10443.296843] Key type lgssc unregistered [10443.418662] LNet: 387136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10443.422032] LNetError: 387136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10443.430140] LNet: Removed LNI 192.168.201.37@tcp [10443.677144] Key type .llcrypt unregistered [10443.678850] Key type ._llcrypt unregistered [10443.927863] Key type ._llcrypt registered [10443.929622] Key type .llcrypt registered [10444.175070] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10444.182044] alg: No test for adler32 (adler32-zlib) [10445.057592] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10445.154584] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10446.751612] Key type lgssc registered [10447.278109] Lustre: Echo OBD driver; http://www.lustre.org/ [10451.264262] Lustre: DEBUG MARKER: Iteration 21 [10451.384335] LustreError: 387966:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10451.384373] LustreError: 387967:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10451.391227] LustreError: 387966:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10452.456755] Lustre: Mounted lustre-client [10452.925301] LustreError: 388083:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c38000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10452.930382] LustreError: 388082:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10452.931124] LustreError: 388083:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10452.977883] Lustre: Unmounted lustre-client [10454.068064] Key type lgssc unregistered [10454.202261] LNet: 388325:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10454.205339] LNetError: 388325:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10454.218668] LNet: Removed LNI 192.168.201.37@tcp [10454.504213] Key type .llcrypt unregistered [10454.505954] Key type ._llcrypt unregistered [10454.762535] Key type ._llcrypt registered [10454.764523] Key type .llcrypt registered [10454.964161] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10454.974493] alg: No test for adler32 (adler32-zlib) [10455.848192] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10455.945893] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10457.543186] Key type lgssc registered [10458.017944] Lustre: Echo OBD driver; http://www.lustre.org/ [10462.194500] Lustre: DEBUG MARKER: Iteration 22 [10462.322706] LustreError: 389154:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10462.322743] LustreError: 389155:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10462.329176] LustreError: 389154:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10463.398267] Lustre: Mounted lustre-client [10463.399913] Lustre: Skipped 1 previous similar message [10463.872210] LustreError: 389261:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df04ee8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10463.881045] LustreError: 389261:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10463.884532] LustreError: 389261:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [10463.921038] Lustre: Unmounted lustre-client [10464.999469] Key type lgssc unregistered [10465.124832] LNet: 389509:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10465.128047] LNetError: 389509:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10465.140697] LNet: Removed LNI 192.168.201.37@tcp [10465.421198] Key type .llcrypt unregistered [10465.423443] Key type ._llcrypt unregistered [10465.689467] Key type ._llcrypt registered [10465.691751] Key type .llcrypt registered [10465.876974] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10465.891486] alg: No test for adler32 (adler32-zlib) [10466.782422] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10466.882967] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10468.487213] Key type lgssc registered [10468.980756] Lustre: Echo OBD driver; http://www.lustre.org/ [10473.348865] Lustre: DEBUG MARKER: Iteration 23 [10473.465708] LustreError: 390336:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10473.465759] LustreError: 390337:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10473.473166] LustreError: 390336:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10474.560309] Lustre: Mounted lustre-client [10475.046783] LustreError: 390452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df20fa5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10475.051334] LustreError: 390452:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10475.056816] LustreError: 390451:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10475.059524] LustreError: 390451:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10475.094411] Lustre: Unmounted lustre-client [10476.242937] Key type lgssc unregistered [10476.377539] LNet: 390693:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10476.381162] LNetError: 390693:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10476.389971] LNet: Removed LNI 192.168.201.37@tcp [10476.673150] Key type .llcrypt unregistered [10476.675461] Key type ._llcrypt unregistered [10476.935817] Key type ._llcrypt registered [10476.938233] Key type .llcrypt registered [10477.175799] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10477.186868] alg: No test for adler32 (adler32-zlib) [10478.061760] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10478.158736] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10479.751319] Key type lgssc registered [10480.206982] Lustre: Echo OBD driver; http://www.lustre.org/ [10484.124580] Lustre: DEBUG MARKER: Iteration 24 [10484.238863] LustreError: 391523:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10484.239318] LustreError: 391524:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10484.246592] LustreError: 391523:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10485.319765] Lustre: Mounted lustre-client [10485.887908] LustreError: 391634:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0668a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10485.895797] LustreError: 391634:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10485.924083] Lustre: Unmounted lustre-client [10487.055884] Key type lgssc unregistered [10487.186485] LNet: 391884:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10487.189533] LNetError: 391884:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10487.196869] LNet: Removed LNI 192.168.201.37@tcp [10487.454162] Key type .llcrypt unregistered [10487.455799] Key type ._llcrypt unregistered [10487.708645] Key type ._llcrypt registered [10487.710324] Key type .llcrypt registered [10487.932684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10487.939441] alg: No test for adler32 (adler32-zlib) [10488.826909] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10488.928202] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10490.527183] Key type lgssc registered [10490.986702] Lustre: Echo OBD driver; http://www.lustre.org/ [10495.233191] Lustre: DEBUG MARKER: Iteration 25 [10495.348261] LustreError: 392714:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10495.348589] LustreError: 392715:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10495.356078] LustreError: 392714:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10496.425304] Lustre: Mounted lustre-client [10496.942045] LustreError: 392826:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df208a5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10496.949793] LustreError: 392826:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10497.001339] Lustre: Unmounted lustre-client [10498.060879] Key type lgssc unregistered [10498.175651] LNet: 393069:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10498.179701] LNetError: 393069:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10498.194531] LNet: Removed LNI 192.168.201.37@tcp [10498.462172] Key type .llcrypt unregistered [10498.463624] Key type ._llcrypt unregistered [10498.754302] Key type ._llcrypt registered [10498.755694] Key type .llcrypt registered [10498.975661] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10498.982362] alg: No test for adler32 (adler32-zlib) [10499.851423] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10499.950474] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10501.543215] Key type lgssc registered [10501.997577] Lustre: Echo OBD driver; http://www.lustre.org/ [10505.926309] Lustre: DEBUG MARKER: Iteration 26 [10506.059286] LustreError: 393898:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10506.059746] LustreError: 393899:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10506.067533] LustreError: 393898:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10507.136082] Lustre: Mounted lustre-client [10507.630565] LustreError: 394013:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0858d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10507.635405] LustreError: 394014:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10507.636088] LustreError: 394013:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10507.691456] Lustre: Unmounted lustre-client [10508.739241] Key type lgssc unregistered [10508.861479] LNet: 394256:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10508.864527] LNetError: 394256:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10508.874307] LNet: Removed LNI 192.168.201.37@tcp [10509.145177] Key type .llcrypt unregistered [10509.146643] Key type ._llcrypt unregistered [10509.406328] Key type ._llcrypt registered [10509.407557] Key type .llcrypt registered [10509.613671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10509.619679] alg: No test for adler32 (adler32-zlib) [10510.476461] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10510.559463] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10512.151283] Key type lgssc registered [10512.605294] Lustre: Echo OBD driver; http://www.lustre.org/ [10516.591368] Lustre: DEBUG MARKER: Iteration 27 [10516.719299] LustreError: 395085:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10516.719674] LustreError: 395086:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10516.728563] LustreError: 395085:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10517.809506] Lustre: Mounted lustre-client [10518.309209] LustreError: 395200:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df08c56000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10518.313129] LustreError: 395200:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10518.313629] LustreError: 395199:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10518.384043] Lustre: Unmounted lustre-client [10519.455891] Key type lgssc unregistered [10519.581495] LNet: 395442:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10519.586656] LNetError: 395442:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10519.597798] LNet: Removed LNI 192.168.201.37@tcp [10519.853172] Key type .llcrypt unregistered [10519.854844] Key type ._llcrypt unregistered [10520.095135] Key type ._llcrypt registered [10520.096751] Key type .llcrypt registered [10520.311022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10520.317970] alg: No test for adler32 (adler32-zlib) [10521.190738] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10521.282691] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10522.879522] Key type lgssc registered [10523.329521] Lustre: Echo OBD driver; http://www.lustre.org/ [10527.582103] Lustre: DEBUG MARKER: Iteration 28 [10527.764087] LustreError: 396271:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10527.764326] LustreError: 396272:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10527.769757] LustreError: 396271:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10528.848543] Lustre: Mounted lustre-client [10529.300494] LustreError: 396386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df03ce7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10529.305266] LustreError: 396387:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10529.305896] LustreError: 396386:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10529.339831] Lustre: Unmounted lustre-client [10529.341490] Lustre: Skipped 1 previous similar message [10530.350618] Key type lgssc unregistered [10530.474546] LNet: 396630:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10530.477365] LNetError: 396630:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10530.486610] LNet: Removed LNI 192.168.201.37@tcp [10530.728149] Key type .llcrypt unregistered [10530.729343] Key type ._llcrypt unregistered [10531.039444] Key type ._llcrypt registered [10531.047297] Key type .llcrypt registered [10531.228973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10531.236323] alg: No test for adler32 (adler32-zlib) [10532.136446] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10532.238960] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10533.831131] Key type lgssc registered [10534.255342] Lustre: Echo OBD driver; http://www.lustre.org/ [10538.164840] Lustre: DEBUG MARKER: Iteration 29 [10538.263321] LustreError: 397458:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10538.263845] LustreError: 397459:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10538.269292] LustreError: 397458:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10539.330382] Lustre: Mounted lustre-client [10539.779709] LustreError: 397569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df12cdf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10539.787715] LustreError: 397569:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10539.831407] Lustre: Unmounted lustre-client [10540.847551] Key type lgssc unregistered [10540.959716] LNet: 397811:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10540.962688] LNetError: 397811:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10540.970531] LNet: Removed LNI 192.168.201.37@tcp [10541.223166] Key type .llcrypt unregistered [10541.224423] Key type ._llcrypt unregistered [10541.528347] Key type ._llcrypt registered [10541.529755] Key type .llcrypt registered [10541.809810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10541.817716] alg: No test for adler32 (adler32-zlib) [10542.703759] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10542.809644] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10544.415194] Key type lgssc registered [10544.813727] Lustre: Echo OBD driver; http://www.lustre.org/ [10548.525555] Lustre: DEBUG MARKER: Iteration 30 [10548.620084] LustreError: 398640:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10548.622076] LustreError: 398641:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10548.625354] LustreError: 398640:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10549.678680] Lustre: Mounted lustre-client [10549.680082] Lustre: Skipped 1 previous similar message [10550.163862] LustreError: 398751:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df104c9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10550.172448] LustreError: 398751:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10550.260035] Lustre: Unmounted lustre-client [10550.261326] Lustre: Skipped 1 previous similar message [10551.212877] Key type lgssc unregistered [10551.327560] LNet: 398993:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10551.330149] LNetError: 398993:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10551.337759] LNet: Removed LNI 192.168.201.37@tcp [10551.583116] Key type .llcrypt unregistered [10551.585365] Key type ._llcrypt unregistered [10551.889928] Key type ._llcrypt registered [10551.891477] Key type .llcrypt registered [10552.124303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10552.142780] alg: No test for adler32 (adler32-zlib) [10553.023094] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10553.127521] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10554.727197] Key type lgssc registered [10555.167101] Lustre: Echo OBD driver; http://www.lustre.org/ [10559.014274] Lustre: DEBUG MARKER: Iteration 31 [10559.120385] LustreError: 399820:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10559.121870] LustreError: 399821:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10559.125739] LustreError: 399820:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10560.192143] Lustre: Mounted lustre-client [10560.655252] LustreError: 399932:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df04c60000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10560.658283] LustreError: 399931:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10560.660753] LustreError: 399932:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10560.698064] Lustre: Unmounted lustre-client [10561.736027] Key type lgssc unregistered [10561.857808] LNet: 400173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10561.860873] LNetError: 400173:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10561.871210] LNet: Removed LNI 192.168.201.37@tcp [10562.121147] Key type .llcrypt unregistered [10562.123121] Key type ._llcrypt unregistered [10562.366398] Key type ._llcrypt registered [10562.369157] Key type .llcrypt registered [10562.593611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10562.602867] alg: No test for adler32 (adler32-zlib) [10563.488114] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10563.582677] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10565.175170] Key type lgssc registered [10565.599741] Lustre: Echo OBD driver; http://www.lustre.org/ [10569.358274] Lustre: DEBUG MARKER: Iteration 32 [10569.480733] LustreError: 401001:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10569.480826] LustreError: 401002:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10569.487776] LustreError: 401001:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10570.557464] Lustre: Mounted lustre-client [10570.559104] Lustre: Skipped 1 previous similar message [10571.017304] LustreError: 401114:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c3f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10571.019849] LustreError: 401115:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10571.022100] LustreError: 401114:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10571.070178] Lustre: Unmounted lustre-client [10572.078585] Key type lgssc unregistered [10572.196631] LNet: 401357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10572.199302] LNetError: 401357:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10572.208316] LNet: Removed LNI 192.168.201.37@tcp [10572.488168] Key type .llcrypt unregistered [10572.489579] Key type ._llcrypt unregistered [10572.759685] Key type ._llcrypt registered [10572.761586] Key type .llcrypt registered [10572.942939] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10572.950905] alg: No test for adler32 (adler32-zlib) [10573.819617] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10573.907202] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10575.495246] Key type lgssc registered [10575.891675] Lustre: Echo OBD driver; http://www.lustre.org/ [10579.792306] Lustre: DEBUG MARKER: Iteration 33 [10579.917057] LustreError: 402186:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10579.917154] LustreError: 402187:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10579.923689] LustreError: 402186:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10580.997022] Lustre: Mounted lustre-client [10581.506038] LustreError: 402300:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df084a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10581.510892] LustreError: 402300:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10581.514988] LustreError: 402301:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10581.517588] LustreError: 402301:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10581.550110] Lustre: Unmounted lustre-client [10582.606427] Key type lgssc unregistered [10582.725703] LNet: 402544:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10582.729172] LNetError: 402544:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10582.738117] LNet: Removed LNI 192.168.201.37@tcp [10583.001149] Key type .llcrypt unregistered [10583.003060] Key type ._llcrypt unregistered [10583.275915] Key type ._llcrypt registered [10583.277537] Key type .llcrypt registered [10583.457081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10583.463066] alg: No test for adler32 (adler32-zlib) [10584.329221] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10584.423469] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10586.015145] Key type lgssc registered [10586.449327] Lustre: Echo OBD driver; http://www.lustre.org/ [10590.362267] Lustre: DEBUG MARKER: Iteration 34 [10590.465147] LustreError: 403371:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10590.471491] LustreError: 403378:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10590.474360] LustreError: 403371:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [10591.534985] Lustre: Mounted lustre-client [10591.536623] Lustre: Skipped 1 previous similar message [10592.089623] LustreError: 403487:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df12d6c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10592.093603] LustreError: 403487:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10592.094095] LustreError: 403488:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10592.161933] Lustre: Unmounted lustre-client [10593.354645] Key type lgssc unregistered [10593.486666] LNet: 403730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10593.490381] LNetError: 403730:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10593.505519] LNet: Removed LNI 192.168.201.37@tcp [10593.774198] Key type .llcrypt unregistered [10593.775731] Key type ._llcrypt unregistered [10594.076509] Key type ._llcrypt registered [10594.077909] Key type .llcrypt registered [10594.278335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10594.284228] alg: No test for adler32 (adler32-zlib) [10595.155435] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10595.247867] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10596.839224] Key type lgssc registered [10597.251727] Lustre: Echo OBD driver; http://www.lustre.org/ [10601.474728] Lustre: DEBUG MARKER: Iteration 35 [10601.592826] LustreError: 404558:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10601.594857] LustreError: 404564:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10601.599971] LustreError: 404558:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10602.681606] Lustre: Mounted lustre-client [10602.683736] Lustre: Skipped 1 previous similar message [10603.226337] LustreError: 404669:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df12db0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10603.235516] LustreError: 404669:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10603.265182] Lustre: Unmounted lustre-client [10604.473048] Key type lgssc unregistered [10604.608112] LNet: 404917:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10604.610797] LNetError: 404917:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10604.621903] LNet: Removed LNI 192.168.201.37@tcp [10604.903289] Key type .llcrypt unregistered [10604.904885] Key type ._llcrypt unregistered [10605.167756] Key type ._llcrypt registered [10605.170783] Key type .llcrypt registered [10605.405805] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10605.412030] alg: No test for adler32 (adler32-zlib) [10606.306463] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10606.412562] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10608.007200] Key type lgssc registered [10608.473311] Lustre: Echo OBD driver; http://www.lustre.org/ [10612.588664] Lustre: DEBUG MARKER: Iteration 36 [10612.712279] LustreError: 405746:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10612.712723] LustreError: 405747:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10612.718774] LustreError: 405746:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10613.784840] Lustre: Mounted lustre-client [10614.356599] LustreError: 405861:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c39800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10614.361650] LustreError: 405861:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10614.361738] LustreError: 405860:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10614.428146] Lustre: Unmounted lustre-client [10615.539089] Key type lgssc unregistered [10615.678398] LNet: 406104:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10615.681819] LNetError: 406104:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10615.694316] LNet: Removed LNI 192.168.201.37@tcp [10615.964139] Key type .llcrypt unregistered [10615.965717] Key type ._llcrypt unregistered [10616.267125] Key type ._llcrypt registered [10616.268510] Key type .llcrypt registered [10616.468236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10616.474760] alg: No test for adler32 (adler32-zlib) [10617.367823] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10617.474221] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10619.071287] Key type lgssc registered [10619.509980] Lustre: Echo OBD driver; http://www.lustre.org/ [10623.641310] Lustre: DEBUG MARKER: Iteration 37 [10623.782814] LustreError: 406932:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10623.782893] LustreError: 406933:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10623.789391] LustreError: 406932:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10624.855543] Lustre: Mounted lustre-client [10625.359980] LustreError: 407048:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df12db2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10625.367785] LustreError: 407048:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10625.370509] LustreError: 407048:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10625.421081] Lustre: Unmounted lustre-client [10626.454716] Key type lgssc unregistered [10626.569740] LNet: 407291:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10626.572420] LNetError: 407291:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10626.580701] LNet: Removed LNI 192.168.201.37@tcp [10626.826149] Key type .llcrypt unregistered [10626.827507] Key type ._llcrypt unregistered [10627.122940] Key type ._llcrypt registered [10627.124641] Key type .llcrypt registered [10627.312702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10627.319239] alg: No test for adler32 (adler32-zlib) [10628.195742] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10628.292631] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10629.879208] Key type lgssc registered [10630.340601] Lustre: Echo OBD driver; http://www.lustre.org/ [10634.268035] Lustre: DEBUG MARKER: Iteration 38 [10634.377948] LustreError: 408119:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10634.380094] LustreError: 408120:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10634.384935] LustreError: 408119:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10635.453878] Lustre: Mounted lustre-client [10635.455165] Lustre: Skipped 1 previous similar message [10635.950396] LustreError: 408230:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c38000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10635.952675] LustreError: 408229:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10635.954585] LustreError: 408230:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10636.003391] Lustre: Unmounted lustre-client [10637.073298] Key type lgssc unregistered [10637.198413] LNet: 408471:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10637.201713] LNetError: 408471:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10637.210747] LNet: Removed LNI 192.168.201.37@tcp [10637.476188] Key type .llcrypt unregistered [10637.477669] Key type ._llcrypt unregistered [10637.793701] Key type ._llcrypt registered [10637.815027] Key type .llcrypt registered [10637.977883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10637.985416] alg: No test for adler32 (adler32-zlib) [10638.861079] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10638.954344] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10640.543193] Key type lgssc registered [10640.982115] Lustre: Echo OBD driver; http://www.lustre.org/ [10644.746282] Lustre: DEBUG MARKER: Iteration 39 [10644.856499] LustreError: 409299:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10644.856612] LustreError: 409300:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10644.863449] LustreError: 409299:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10645.923409] Lustre: Mounted lustre-client [10645.924766] Lustre: Skipped 1 previous similar message [10646.379916] LustreError: 409412:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df06675000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10646.385253] LustreError: 409413:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10646.385452] LustreError: 409412:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10646.432847] Lustre: Unmounted lustre-client [10647.474934] Key type lgssc unregistered [10647.597466] LNet: 409656:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10647.600805] LNetError: 409656:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10647.612574] LNet: Removed LNI 192.168.201.37@tcp [10647.878141] Key type .llcrypt unregistered [10647.879553] Key type ._llcrypt unregistered [10648.181848] Key type ._llcrypt registered [10648.183526] Key type .llcrypt registered [10648.378264] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10648.385434] alg: No test for adler32 (adler32-zlib) [10649.255674] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10649.352738] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10650.951192] Key type lgssc registered [10651.414498] Lustre: Echo OBD driver; http://www.lustre.org/ [10655.505171] Lustre: DEBUG MARKER: Iteration 40 [10655.621993] LustreError: 410483:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10655.622054] LustreError: 410484:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10655.629345] LustreError: 410483:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10656.705834] Lustre: Mounted lustre-client [10657.196171] LustreError: 410599:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df17f7c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10657.201663] LustreError: 410599:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10657.201835] LustreError: 410600:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10657.207109] LustreError: 410600:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10657.241979] Lustre: Unmounted lustre-client [10658.280053] Key type lgssc unregistered [10658.407701] LNet: 410842:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10658.411354] LNetError: 410842:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10658.423789] LNet: Removed LNI 192.168.201.37@tcp [10658.663163] Key type .llcrypt unregistered [10658.665741] Key type ._llcrypt unregistered [10658.926606] Key type ._llcrypt registered [10658.927705] Key type .llcrypt registered [10659.135834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10659.141913] alg: No test for adler32 (adler32-zlib) [10660.015585] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10660.108830] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10661.703190] Key type lgssc registered [10662.162382] Lustre: Echo OBD driver; http://www.lustre.org/ [10666.049854] Lustre: DEBUG MARKER: Iteration 41 [10666.163900] LustreError: 411671:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10666.164473] LustreError: 411672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10666.170136] LustreError: 411671:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10667.232514] Lustre: Mounted lustre-client [10667.713864] LustreError: 411786:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df104c8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10667.720805] LustreError: 411786:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10667.773076] Lustre: Unmounted lustre-client [10668.849529] Key type lgssc unregistered [10668.975853] LNet: 412028:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10668.979319] LNetError: 412028:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10668.987725] LNet: Removed LNI 192.168.201.37@tcp [10669.242162] Key type .llcrypt unregistered [10669.243792] Key type ._llcrypt unregistered [10669.552128] Key type ._llcrypt registered [10669.553482] Key type .llcrypt registered [10669.742664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10669.751312] alg: No test for adler32 (adler32-zlib) [10670.635172] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10670.727454] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10672.319170] Key type lgssc registered [10672.832986] Lustre: Echo OBD driver; http://www.lustre.org/ [10677.303489] Lustre: DEBUG MARKER: Iteration 42 [10677.424479] LustreError: 412857:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10677.424613] LustreError: 412858:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10677.431498] LustreError: 412857:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10678.501949] Lustre: Mounted lustre-client [10678.994714] LustreError: 412974:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df10850000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10678.999320] LustreError: 412974:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10679.003871] LustreError: 412973:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10679.057090] Lustre: Unmounted lustre-client [10680.109892] Key type lgssc unregistered [10680.232464] LNet: 413215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10680.236091] LNetError: 413215:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10680.245628] LNet: Removed LNI 192.168.201.37@tcp [10680.506148] Key type .llcrypt unregistered [10680.508051] Key type ._llcrypt unregistered [10680.804300] Key type ._llcrypt registered [10680.806415] Key type .llcrypt registered [10680.998091] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10681.005224] alg: No test for adler32 (adler32-zlib) [10681.894176] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10681.993513] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10683.583143] Key type lgssc registered [10684.033183] Lustre: Echo OBD driver; http://www.lustre.org/ [10688.201828] Lustre: DEBUG MARKER: Iteration 43 [10688.320938] LustreError: 414042:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10688.322367] LustreError: 414043:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10688.328068] LustreError: 414042:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10689.392348] Lustre: Mounted lustre-client [10689.394423] Lustre: Skipped 1 previous similar message [10689.859032] LustreError: 414155:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df04b56800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10689.864195] LustreError: 414155:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10689.868281] LustreError: 414156:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10689.871433] LustreError: 414156:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10689.903114] Lustre: Unmounted lustre-client [10690.981761] Key type lgssc unregistered [10691.100523] LNet: 414398:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10691.104125] LNetError: 414398:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10691.113856] LNet: Removed LNI 192.168.201.37@tcp [10691.363170] Key type .llcrypt unregistered [10691.364780] Key type ._llcrypt unregistered [10691.689031] Key type ._llcrypt registered [10691.690524] Key type .llcrypt registered [10691.867295] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10691.873742] alg: No test for adler32 (adler32-zlib) [10692.756789] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10692.848890] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10694.439178] Key type lgssc registered [10694.895495] Lustre: Echo OBD driver; http://www.lustre.org/ [10699.123557] Lustre: DEBUG MARKER: Iteration 44 [10699.256442] LustreError: 415227:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10699.257102] LustreError: 415226:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10699.266281] LustreError: 415227:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10700.368530] Lustre: Mounted lustre-client [10700.370040] Lustre: Skipped 1 previous similar message [10700.902360] LustreError: 415337:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02b00800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10700.913469] LustreError: 415337:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10700.956436] Lustre: Unmounted lustre-client [10702.009618] Key type lgssc unregistered [10702.130781] LNet: 415586:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10702.134031] LNetError: 415586:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10702.142893] LNet: Removed LNI 192.168.201.37@tcp [10702.409149] Key type .llcrypt unregistered [10702.410594] Key type ._llcrypt unregistered [10702.693376] Key type ._llcrypt registered [10702.694823] Key type .llcrypt registered [10702.888645] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10702.896169] alg: No test for adler32 (adler32-zlib) [10703.764476] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10703.861574] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10705.455188] Key type lgssc registered [10705.922842] Lustre: Echo OBD driver; http://www.lustre.org/ [10710.041340] Lustre: DEBUG MARKER: Iteration 45 [10710.183252] LustreError: 416417:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10710.183628] LustreError: 416416:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10710.190093] LustreError: 416417:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10711.279399] Lustre: Mounted lustre-client [10711.281042] Lustre: Skipped 1 previous similar message [10711.763144] LustreError: 416529:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df06676800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10711.766710] LustreError: 416530:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10711.768113] LustreError: 416529:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10711.808210] Lustre: Unmounted lustre-client [10712.873578] Key type lgssc unregistered [10713.003866] LNet: 416773:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10713.007051] LNetError: 416773:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10713.016815] LNet: Removed LNI 192.168.201.37@tcp [10713.270549] Key type .llcrypt unregistered [10713.271809] Key type ._llcrypt unregistered [10713.514714] Key type ._llcrypt registered [10713.516386] Key type .llcrypt registered [10713.720430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10713.735996] alg: No test for adler32 (adler32-zlib) [10714.615347] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10714.706511] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10716.295475] Key type lgssc registered [10716.733414] Lustre: Echo OBD driver; http://www.lustre.org/ [10720.791343] Lustre: DEBUG MARKER: Iteration 46 [10720.894532] LustreError: 417600:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10720.894913] LustreError: 417601:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10720.900294] LustreError: 417600:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10721.970629] Lustre: Mounted lustre-client [10721.971856] Lustre: Skipped 1 previous similar message [10722.434816] LustreError: 417713:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df04ee8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10722.440179] LustreError: 417714:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10722.440396] LustreError: 417713:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10722.484511] Lustre: Unmounted lustre-client [10723.543329] Key type lgssc unregistered [10723.661548] LNet: 417956:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10723.664789] LNetError: 417956:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10723.675077] LNet: Removed LNI 192.168.201.37@tcp [10723.939129] Key type .llcrypt unregistered [10723.940832] Key type ._llcrypt unregistered [10724.246827] Key type ._llcrypt registered [10724.248595] Key type .llcrypt registered [10724.445185] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10724.452238] alg: No test for adler32 (adler32-zlib) [10725.333883] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10725.442296] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10727.039185] Key type lgssc registered [10727.475051] Lustre: Echo OBD driver; http://www.lustre.org/ [10731.795414] Lustre: DEBUG MARKER: Iteration 47 [10731.905157] LustreError: 418784:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10731.905269] LustreError: 418785:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10731.912411] LustreError: 418784:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10732.983101] Lustre: Mounted lustre-client [10732.984658] Lustre: Skipped 1 previous similar message [10733.452875] LustreError: 418896:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0346f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10733.456734] LustreError: 418897:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10733.458035] LustreError: 418896:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10733.503876] Lustre: Unmounted lustre-client [10734.541667] Key type lgssc unregistered [10734.671028] LNet: 419139:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10734.674051] LNetError: 419139:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10734.681574] LNet: Removed LNI 192.168.201.37@tcp [10734.942178] Key type .llcrypt unregistered [10734.943769] Key type ._llcrypt unregistered [10735.267111] Key type ._llcrypt registered [10735.268660] Key type .llcrypt registered [10735.433800] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10735.440801] alg: No test for adler32 (adler32-zlib) [10736.326747] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10736.420228] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10738.015915] Key type lgssc registered [10738.500924] Lustre: Echo OBD driver; http://www.lustre.org/ [10743.076232] Lustre: DEBUG MARKER: Iteration 48 [10743.207716] LustreError: 419967:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10743.208956] LustreError: 419968:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10743.215157] LustreError: 419967:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10744.304691] Lustre: Mounted lustre-client [10744.306638] Lustre: Skipped 1 previous similar message [10744.792043] LustreError: 420073:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df104ce800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10744.800676] LustreError: 420073:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10744.836330] Lustre: Unmounted lustre-client [10745.929874] Key type lgssc unregistered [10746.045669] LNet: 420323:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10746.048667] LNetError: 420323:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10746.057453] LNet: Removed LNI 192.168.201.37@tcp [10746.325127] Key type .llcrypt unregistered [10746.326731] Key type ._llcrypt unregistered [10746.568732] Key type ._llcrypt registered [10746.570694] Key type .llcrypt registered [10746.794055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10746.802117] alg: No test for adler32 (adler32-zlib) [10747.682308] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10747.777906] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10749.375181] Key type lgssc registered [10749.894613] Lustre: Echo OBD driver; http://www.lustre.org/ [10754.209313] Lustre: DEBUG MARKER: Iteration 49 [10754.339851] LustreError: 421151:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10754.340088] LustreError: 421152:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10754.347335] LustreError: 421151:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10755.421482] Lustre: Mounted lustre-client [10755.422881] Lustre: Skipped 1 previous similar message [10755.890286] LustreError: 421264:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df06689000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10755.894245] LustreError: 421266:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10755.895290] LustreError: 421264:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10755.941898] Lustre: Unmounted lustre-client [10757.016847] Key type lgssc unregistered [10757.141225] LNet: 421507:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10757.144819] LNetError: 421507:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10757.156926] LNet: Removed LNI 192.168.201.37@tcp [10757.441549] Key type .llcrypt unregistered [10757.443866] Key type ._llcrypt unregistered [10757.702386] Key type ._llcrypt registered [10757.705310] Key type .llcrypt registered [10757.921332] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10757.929261] alg: No test for adler32 (adler32-zlib) [10758.804774] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10758.898259] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10760.487197] Key type lgssc registered [10760.923393] Lustre: Echo OBD driver; http://www.lustre.org/ [10765.220293] Lustre: DEBUG MARKER: Iteration 50 [10765.351348] LustreError: 422348:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10765.351417] LustreError: 422349:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10765.357580] LustreError: 422348:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10766.424915] Lustre: Mounted lustre-client [10766.424915] Lustre: Mounted lustre-client [10766.922837] LustreError: 422464:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df0668e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10766.925253] LustreError: 422463:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10766.927548] LustreError: 422464:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10766.974205] Lustre: Unmounted lustre-client [10768.025245] Key type lgssc unregistered [10768.155693] LNet: 422707:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10768.158627] LNetError: 422707:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10768.165745] LNet: Removed LNI 192.168.201.37@tcp [10768.410137] Key type .llcrypt unregistered [10768.411279] Key type ._llcrypt unregistered [10768.738738] Key type ._llcrypt registered [10768.740411] Key type .llcrypt registered [10768.895882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10768.902359] alg: No test for adler32 (adler32-zlib) [10769.774593] Lustre: Lustre: Build Version: 2.16.61_46_gcd0e473 [10769.869390] LNet: Added LNI 192.168.201.37@tcp [8/256/0/180] [10771.463260] Key type lgssc registered [10771.899612] Lustre: Echo OBD driver; http://www.lustre.org/ [10776.685878] Lustre: Mounted lustre-client [10779.306836] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 23:51:17 (1763355077) [10795.999191] Lustre: 424061:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355078/real 1763355078] req@ffff88df084eb480 x1849011803793280/t0(0) o36->lustre-MDT0000-mdc-ffff88df20e0b800@192.168.201.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1763355094 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10796.009121] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10796.022411] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10811.360000] Lustre: 424061:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355094/real 1763355094] req@ffff88df084eb480 x1849011803793280/t0(0) o36->lustre-MDT0000-mdc-ffff88df20e0b800@192.168.201.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1763355110 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10811.373987] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10811.386275] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10827.743256] Lustre: 424061:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355110/real 1763355110] req@ffff88df084eb480 x1849011803793280/t0(0) o36->lustre-MDT0000-mdc-ffff88df20e0b800@192.168.201.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1763355126 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10827.753023] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10827.766393] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10844.127189] Lustre: 424061:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355126/real 1763355126] req@ffff88df084eb480 x1849011803793280/t0(0) o36->lustre-MDT0000-mdc-ffff88df20e0b800@192.168.201.137@tcp:12/10 lens 496/440 e 0 to 1 dl 1763355142 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10844.138937] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection to lustre-MDT0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10844.151293] Lustre: lustre-MDT0000-mdc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10844.749143] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 23:52:22 (1763355142) [10850.745868] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 23:52:28 (1763355148) [10854.431862] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 23:52:32 (1763355152) [10856.850538] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 23:52:35 (1763355155) [10864.591046] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 23:52:42 (1763355162) [10878.530947] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 23:52:56 (1763355176) [10880.963864] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 23:52:59 (1763355179) [10898.335266] Lustre: 422899:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355181/real 1763355181] req@ffff88df359ed180 x1849011804821888/t0(0) o4->lustre-OST0000-osc-ffff88df20e0b800@192.168.201.137@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763355197 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10898.335317] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10898.343977] Lustre: 422899:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [10914.783167] Lustre: 422896:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355197/real 1763355197] req@ffff88df08501f80 x1849011804823424/t0(0) o4->lustre-OST0000-osc-ffff88df20e0b800@192.168.201.137@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763355213 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [10914.783197] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10914.793549] Lustre: 422896:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [10914.808103] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10931.167178] Lustre: 422899:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763355213/real 1763355213] req@ffff88df359ed180 x1849011804821888/t0(0) o4->lustre-OST0000-osc-ffff88df20e0b800@192.168.201.137@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763355229 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10931.167206] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection to lustre-OST0000 (at 192.168.201.137@tcp) was lost; in progress operations using this service will wait for recovery to complete [10931.177555] Lustre: 422899:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [10931.188596] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10962.914319] Lustre: lustre-OST0000-osc-ffff88df20e0b800: Connection restored to 192.168.201.137@tcp (at 192.168.201.137@tcp) [10962.918646] Lustre: Skipped 1 previous similar message [10970.015358] Lustre: DEBUG MARKER: oleg137-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff88df02c3d000.ost_server_uuid 50 [10970.545218] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff88df02c3d000.ost_server_uuid in IDLE state after 0 sec [10971.121492] Lustre: DEBUG MARKER: cleanup: ====================================================== [10971.709315] Lustre: DEBUG MARKER: == sanityn test complete, duration 10698 sec ============= 23:54:29 (1763355269) [10972.264619] Lustre: DEBUG MARKER: === sanityn: start cleanup 23:54:30 (1763355270) === [11038.550378] LustreError: 430298:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df02c3d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11038.554714] LustreError: 430298:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11038.572238] Lustre: Unmounted lustre-client [11039.761410] Lustre: DEBUG MARKER: === sanityn: finish cleanup 23:55:38 (1763355338) === [11040.044523] LustreError: 430602:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff88df20e0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11040.048026] LustreError: 430602:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11040.053581] LustreError: 430602:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11040.056302] LustreError: 430602:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11040.085700] Lustre: Unmounted lustre-client [11077.048285] Key type lgssc unregistered [11077.171610] LNet: 431285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11077.175099] LNetError: 431285:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11077.182720] LNet: Removed LNI 192.168.201.37@tcp [11077.452321] Key type .llcrypt unregistered [11077.453781] Key type ._llcrypt unregistered