[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 493233454 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524592K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002370] x2apic enabled [ 0.003009] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.007540] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010154] LSM: Security Framework initializing [ 0.011056] Yama: becoming mindful. [ 0.012040] SELinux: Initializing. [ 0.014012] *** VALIDATE selinux *** [ 0.022293] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026716] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027167] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028115] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029115] *** VALIDATE tmpfs *** [ 0.030494] *** VALIDATE proc *** [ 0.031294] *** VALIDATE cgroup *** [ 0.032012] *** VALIDATE cgroup2 *** [ 0.034080] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035148] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037033] Spectre V2 : User space: Vulnerable [ 0.038010] Speculative Store Bypass: Vulnerable [ 0.041443] debug: unmapping init [mem 0xffffffffbbe59000-0xffffffffbbe60fff] [ 0.043165] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044675] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045025] ... version: 2 [ 0.046014] ... bit width: 48 [ 0.047014] ... generic registers: 4 [ 0.048014] ... value mask: 0000ffffffffffff [ 0.049014] ... max period: 00007fffffffffff [ 0.050013] ... fixed-purpose events: 3 [ 0.051012] ... event mask: 000000070000000f [ 0.053242] rcu: Hierarchical SRCU implementation. [ 0.055487] smp: Bringing up secondary CPUs ... [ 0.056601] x86: Booting SMP configuration: [ 0.057019] .... node #0, CPUs: #1 #2 #3 [ 0.061130] smp: Brought up 1 node, 4 CPUs [ 0.063019] smpboot: Max logical packages: 1 [ 0.064023] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.226666] node 0 deferred pages initialised in 160ms [ 0.230231] devtmpfs: initialized [ 0.231436] x86/mm: Memory block size: 128MB [ 0.233364] gcov: version magic: 0x41383552 [ 0.237205] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.238115] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.239493] pinctrl core: initialized pinctrl subsystem [ 0.241409] [ 0.241837] ************************************************************* [ 0.244020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.245014] ** ** [ 0.247017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.249019] ** ** [ 0.252018] ** This means that this kernel is built to expose internal ** [ 0.254014] ** IOMMU data structures, which may compromise security on ** [ 0.255014] ** your system. ** [ 0.257015] ** ** [ 0.259014] ** If you see this message and you are not debugging the ** [ 0.261014] ** kernel, report this immediately to your vendor! ** [ 0.263016] ** ** [ 0.264015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.266016] ************************************************************* [ 0.268800] NET: Registered protocol family 16 [ 0.270477] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.272073] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.274074] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.279081] cpuidle: using governor menu [ 0.280908] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.282566] PCI: Using configuration type 1 for base access [ 0.284158] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.295213] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.297026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.299148] cryptd: max_cpu_qlen set to 1000 [ 0.302144] ACPI: Added _OSI(Module Device) [ 0.303013] ACPI: Added _OSI(Processor Device) [ 0.303945] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.306014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.309679] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.316311] ACPI: Interpreter enabled [ 0.317750] ACPI: PM: (supports S0 S3 S4 S5) [ 0.319018] ACPI: Using IOAPIC for interrupt routing [ 0.320144] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.322442] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.332430] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.334046] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.336025] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.338109] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.342308] acpiphp: Slot [2] registered [ 0.343184] acpiphp: Slot [3] registered [ 0.344104] acpiphp: Slot [4] registered [ 0.345098] acpiphp: Slot [5] registered [ 0.346166] acpiphp: Slot [6] registered [ 0.347119] acpiphp: Slot [7] registered [ 0.348101] acpiphp: Slot [8] registered [ 0.349121] acpiphp: Slot [9] registered [ 0.350131] acpiphp: Slot [10] registered [ 0.352107] acpiphp: Slot [11] registered [ 0.353083] acpiphp: Slot [12] registered [ 0.354067] acpiphp: Slot [13] registered [ 0.355106] acpiphp: Slot [14] registered [ 0.356086] acpiphp: Slot [15] registered [ 0.357120] acpiphp: Slot [16] registered [ 0.358088] acpiphp: Slot [17] registered [ 0.359081] acpiphp: Slot [18] registered [ 0.360116] acpiphp: Slot [19] registered [ 0.361088] acpiphp: Slot [20] registered [ 0.362124] acpiphp: Slot [21] registered [ 0.364179] acpiphp: Slot [22] registered [ 0.366124] acpiphp: Slot [23] registered [ 0.367116] acpiphp: Slot [24] registered [ 0.369119] acpiphp: Slot [25] registered [ 0.370114] acpiphp: Slot [26] registered [ 0.371165] acpiphp: Slot [27] registered [ 0.373132] acpiphp: Slot [28] registered [ 0.375113] acpiphp: Slot [29] registered [ 0.376157] acpiphp: Slot [30] registered [ 0.377113] acpiphp: Slot [31] registered [ 0.378063] PCI host bridge to bus 0000:00 [ 0.379021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.381022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.382019] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.384027] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.386031] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.388026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.389199] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.393393] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.397496] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.402936] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.408067] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.410023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.411016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.413019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.415435] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.417769] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.420059] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.422719] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.427020] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.438024] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.441018] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.445624] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.451018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.455025] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.470021] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.480352] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.494026] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.503023] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.519026] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.530170] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.532378] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.534384] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.536380] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.539245] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.544052] iommu: Default domain type: Passthrough [ 0.545500] SCSI subsystem initialized [ 0.547181] ACPI: bus type USB registered [ 0.548118] usbcore: registered new interface driver usbfs [ 0.550110] usbcore: registered new interface driver hub [ 0.552108] usbcore: registered new device driver usb [ 0.553191] pps_core: LinuxPPS API ver. 1 registered [ 0.555021] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.558082] PTP clock support registered [ 0.562173] EDAC MC: Ver: 3.0.0 [ 0.565236] PCI: Using ACPI for IRQ routing [ 0.566813] NetLabel: Initializing [ 0.568017] NetLabel: domain hash size = 128 [ 0.570013] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.572111] NetLabel: unlabeled traffic allowed by default [ 0.574210] vgaarb: loaded [ 0.576312] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.577017] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.583357] clocksource: Switched to clocksource kvm-clock [ 0.689654] VFS: Disk quotas dquot_6.6.0 [ 0.691144] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.693818] *** VALIDATE ramfs *** [ 0.695121] *** VALIDATE hugetlbfs *** [ 0.696699] pnp: PnP ACPI init [ 0.698861] pnp: PnP ACPI: found 6 devices [ 0.717434] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.720788] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.722964] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.725202] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.727699] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.730298] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.732794] NET: Registered protocol family 2 [ 0.734928] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.739373] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.743044] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.748691] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.752982] TCP: Hash tables configured (established 65536 bind 65536) [ 0.757376] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.761996] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.765483] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.769307] NET: Registered protocol family 1 [ 0.771920] RPC: Registered named UNIX socket transport module. [ 0.775237] RPC: Registered udp transport module. [ 0.776906] RPC: Registered tcp transport module. [ 0.778609] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.781064] NET: Registered protocol family 44 [ 0.783646] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.785493] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.787208] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.789528] PCI: CLS 0 bytes, default 64 [ 0.791268] Unpacking initramfs... [ 2.259353] debug: unmapping init [mem 0xffff9d5a7cc64000-0xffff9d5a7ffcffff] [ 2.262942] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.265488] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.268482] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.769517] Initialise system trusted keyrings [ 2.771290] Key type blacklist registered [ 2.773424] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.783093] zbud: loaded [ 2.786455] *** VALIDATE nfs *** [ 2.787843] *** VALIDATE nfs4 *** [ 2.789541] pstore: using deflate compression [ 2.793024] Platform Keyring initialized [ 2.916558] NET: Registered protocol family 38 [ 2.918376] Key type asymmetric registered [ 2.919932] Asymmetric key parser 'x509' registered [ 2.921282] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.924293] io scheduler mq-deadline registered [ 2.925981] io scheduler kyber registered [ 2.928574] io scheduler bfq registered [ 2.931454] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.935862] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.938334] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.941307] ACPI: Power Button [PWRF] [ 3.033253] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.125832] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.226693] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.259976] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.300288] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.308593] Non-volatile memory driver v1.3 [ 3.310440] Linux agpgart interface v0.103 [ 3.350088] virtio_blk virtio1: [vda] 133800 512-byte logical blocks (68.5 MB/65.3 MiB) [ 3.353275] vda: detected capacity change from 0 to 68505600 [ 3.371539] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.374901] vdb: detected capacity change from 0 to 1073741824 [ 3.382713] libphy: Fixed MDIO Bus: probed [ 3.388634] usbcore: registered new interface driver usbserial_generic [ 3.391223] usbserial: USB Serial support registered for generic [ 3.393443] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.398225] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.400096] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.402710] mousedev: PS/2 mouse device common for all mice [ 3.405783] rtc_cmos 00:05: RTC can wake from S4 [ 3.405932] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.409663] rtc_cmos 00:05: registered as rtc0 [ 3.413314] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.414608] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.417489] intel_pstate: CPU model not supported [ 3.427737] hid: raw HID events driver (C) Jiri Kosina [ 3.427886] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.429967] usbcore: registered new interface driver usbhid [ 3.434958] usbhid: USB HID core driver [ 3.436509] drop_monitor: Initializing network drop monitor service [ 3.438993] Initializing XFRM netlink socket [ 3.441943] NET: Registered protocol family 10 [ 3.445422] Segment Routing with IPv6 [ 3.446542] NET: Registered protocol family 17 [ 3.448618] mpls_gso: MPLS GSO support [ 3.454753] RAS: Correctable Errors collector initialized. [ 3.457071] AVX version of gcm_enc/dec engaged. [ 3.458830] AES CTR mode by8 optimization enabled [ 3.559419] sched_clock: Marking stable (3559376913, 0)->(4471596407, -912219494) [ 3.563045] registered taskstats version 1 [ 3.565573] Loading compiled-in X.509 certificates [ 3.567905] zswap: loaded using pool lzo/zbud [ 3.599765] Key type big_key registered [ 3.613270] Key type encrypted registered [ 3.614846] ima: No TPM chip found, activating TPM-bypass! [ 3.616895] ima: Allocated hash algorithm: sha1 [ 3.618112] ima: No architecture policies found [ 3.619856] evm: Initialising EVM extended attributes: [ 3.621631] evm: security.selinux [ 3.622900] evm: security.ima [ 3.623896] evm: security.capability [ 3.625208] evm: HMAC attrs: 0x1 [ 3.627495] rtc_cmos 00:05: setting system clock to 2025-10-23 08:13:04 UTC (1761207184) [ 3.634455] debug: unmapping init [mem 0xffffffffbce03000-0xffffffffbcffffff] [ 3.636696] debug: unmapping init [mem 0xffffffffbbb82000-0xffffffffbbe58fff] [ 3.644130] Write protecting the kernel read-only data: 28672k [ 3.647752] debug: unmapping init [mem 0xffffffffba203000-0xffffffffba3fffff] [ 3.650763] debug: unmapping init [mem 0xffffffffbab14000-0xffffffffbabfffff] [ 3.687877] 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.695583] systemd[1]: Detected virtualization kvm. [ 3.696833] systemd[1]: Detected architecture x86-64. [ 3.698861] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.723721] systemd[1]: No hostname configured. [ 3.725362] systemd[1]: Set hostname to . [ 3.727491] random: systemd: uninitialized urandom read (16 bytes read) [ 3.730122] systemd[1]: Initializing machine ID from random generator. [ 3.779000] random: ln: uninitialized urandom read (6 bytes read) [ 3.863398] random: systemd: uninitialized urandom read (16 bytes read) [ 3.866496] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.872828] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.877801] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Journal Service... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Swap. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.498831] device-mapper: uevent: version 1.0.3 [ 4.502582] 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 ] [ 5.160719] random: fast init done Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.214130] virtio_net virtio0 ens2: renamed from eth0 [ 5.269455] scsi host0: ata_piix [ 5.272389] scsi host1: ata_piix [ 5.273898] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.276426] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.984086] dracut-initqueue[582]: RTNETLINK answers: File exists [ 10.134566] random: crng init done [ 10.136120] 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.634295] 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 Initrd Default Target. [ 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 Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.915855] printk: systemd: 26 output lines suppressed due to ratelimiting [ 12.213966] SELinux: Disabled at runtime. [ 12.289283] 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.298380] systemd[1]: Detected virtualization kvm. [ 12.300483] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.825135] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.828400] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.833187] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.838393] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.841686] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.848672] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.852628] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ 12.961557] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Starting Apply Kernel Variables... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. Mounting POSIX Message Queue File System... [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. 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 ] Mounted /mnt. [ 13.366361] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 13.747921] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.855492] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.947908] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.960690] EDAC sbridge: Ver: 1.1.2 [ 15.551241] Key type dns_resolver registered [ 16.061668] NFS: Registering the id_resolver key type [ 16.065951] Key type id_resolver registered [ 16.067419] 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 Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Load/Save Random Seed. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg233-client login: [ 37.537054] hrtimer: interrupt took 7002277 ns [ 68.190722] libcfs: loading out-of-tree module taints kernel. [ 68.388424] Key type ._llcrypt registered [ 68.390063] Key type .llcrypt registered [ 69.737841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 69.745820] alg: No test for adler32 (adler32-zlib) [ 71.825820] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 73.115671] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 74.928141] Key type lgssc registered [ 77.328310] Lustre: Echo OBD driver; http://www.lustre.org/ [ 191.046217] Lustre: Mounted lustre-client [ 196.125554] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 216.101370] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing check_logdir /tmp/testlogs/ [ 216.544172] Lustre: lustre-OST0000-osc-ffff9d5ac659f800: disconnect after 23s idle [ 221.009958] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing yml_node [ 225.326562] Lustre: DEBUG MARKER: Client: 2.16.59.38 [ 228.199958] Lustre: DEBUG MARKER: MDS: 2.16.59.38 [ 230.952187] Lustre: DEBUG MARKER: OSS: 2.16.59.38 [ 232.455027] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Oct 23 04:16:52 EDT 2025 [ 249.245662] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 250.635948] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 251.776767] Lustre: DEBUG MARKER: === sanityn: start setup 04:17:11 (1761207431) === [ 252.466662] Lustre: Mounted lustre-client [ 255.389766] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing check_config_client /mnt/lustre [ 269.170572] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 272.867438] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 20s idle [ 272.870375] Lustre: Skipped 1 previous similar message [ 277.145231] Lustre: DEBUG MARKER: === sanityn: finish setup 04:17:36 (1761207456) === [ 278.956131] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 04:17:38 (1761207458) [ 286.101182] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 04:17:45 (1761207465) [ 291.687439] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 04:17:51 (1761207471) [ 297.050924] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 04:17:56 (1761207476) [ 302.763325] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 04:18:02 (1761207482) [ 308.906700] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 04:18:08 (1761207488) [ 315.510686] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 04:18:14 (1761207494) [ 321.975860] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 04:18:21 (1761207501) [ 323.412763] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 325.174073] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 04:18:24 (1761207504) [ 331.369964] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 04:18:30 (1761207510) [ 336.479761] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 04:18:36 (1761207516) [ 342.501911] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 04:18:42 (1761207522) [ 347.995793] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 04:18:47 (1761207527) [ 353.234197] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 04:18:52 (1761207532) [ 358.921252] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 04:18:58 (1761207538) [ 359.905394] Lustre: lustre-OST0001-osc-ffff9d5ac7d1c800: disconnect after 22s idle [ 359.908125] Lustre: Skipped 1 previous similar message [ 364.438765] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 04:19:04 (1761207544) [ 370.082255] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 04:19:09 (1761207549) [ 376.119198] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 04:19:15 (1761207555) [ 382.876046] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 04:19:22 (1761207562) [ 388.583267] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 04:19:28 (1761207568) [ 393.746447] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 04:19:33 (1761207573) [ 394.270658] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 544.622982] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 04:22:04 (1761207724) [ 553.082175] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 04:22:12 (1761207732) [ 559.819404] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 04:22:19 (1761207739) [ 566.357412] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 04:22:25 (1761207745) [ 573.017830] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 04:22:32 (1761207752) [ 580.042462] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 04:22:39 (1761207759) [ 581.688896] Lustre: DEBUG MARKER: chmod [ 588.295879] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 04:22:47 (1761207767) [ 622.689962] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 637.791825] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 04:23:37 (1761207817) [ 684.423774] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 04:24:24 (1761207864) [ 710.059983] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 04:24:49 (1761207889) [ 712.378479] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 714.074522] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 04:24:53 (1761207893) [ 751.635689] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 04:25:31 (1761207931) [ 754.148794] Lustre: lustre-OST0001-osc-ffff9d5ac7d1c800: disconnect after 25s idle [ 757.655099] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 04:25:37 (1761207937) [ 758.506507] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.569323] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.626526] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.692633] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.759468] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.828979] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.902097] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 758.970808] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.049124] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.124862] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.184255] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.242679] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.287817] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.341935] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.421982] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.478772] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.539050] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.593851] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.682846] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.739540] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.799871] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.885806] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 759.953508] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.029057] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.115692] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.199584] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.246521] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.316100] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.388236] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.476975] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.552949] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.634458] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.703784] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.787870] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.875767] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 760.947720] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.012843] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.077152] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.169732] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.225685] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.311588] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.382307] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.433187] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.474068] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.521683] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.594071] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.678514] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.760898] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.805620] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.876146] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 761.942077] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.029175] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.127572] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.204499] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.273745] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.311870] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.376832] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.440801] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.501423] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.554725] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.598687] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.639838] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.680526] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.730150] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.831166] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.910443] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.977288] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.048509] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.090540] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.189358] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.273480] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.336431] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.395069] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.466421] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.524778] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.613849] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.670943] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.733793] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.799235] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.871770] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.935835] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.982599] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.033654] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.078444] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.138623] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.197496] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.279462] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.380890] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.445306] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.504291] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.550978] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.618543] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.673660] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.723297] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.781248] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.883765] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.938350] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.982638] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.046713] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.093825] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.167425] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.243586] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.313760] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.359738] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.419956] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.484139] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.553219] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.622760] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.675733] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.718327] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.770496] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.819140] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.870301] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.955376] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.012369] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.066667] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.120106] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.177894] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.223972] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.274601] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.331932] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.397205] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.450734] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.502833] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.562314] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.645827] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.726814] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.793697] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.857567] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.955747] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.010107] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.065906] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.151471] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.208888] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.285561] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.369123] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.425467] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.518325] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.629089] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.675645] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.760639] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.853693] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.929464] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.007898] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.077408] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.152769] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.224923] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.274588] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.341943] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.421661] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.459978] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.497267] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.543683] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.621496] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.703456] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.763425] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.823546] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.893395] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.962939] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.053657] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.117559] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.181886] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.232642] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.306673] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.347229] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.408316] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.474182] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.543965] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.595451] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.642208] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.692461] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.749385] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.848989] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.922980] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.963045] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.031821] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.118537] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.174906] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.235112] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.314984] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.378726] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.465035] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.552459] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.591561] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.624702] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.675196] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.729163] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.805730] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.861758] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.930896] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.996096] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.061614] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.144514] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.188508] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.268574] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.321349] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.399431] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.457054] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.508197] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.555896] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.631910] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.713039] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.771283] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.833931] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.911941] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.998773] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.075848] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.131568] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.187178] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.243429] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.301334] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.392337] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.476936] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.571961] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.663496] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.718441] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.778488] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.829346] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.901567] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.987071] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.064770] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.135535] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.196130] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.277688] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.350199] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.408329] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.500404] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.577661] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.617373] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.685871] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.786250] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.878227] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.914097] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.944607] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.035972] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.124521] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.219931] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.263409] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.326362] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.392854] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.435785] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.501967] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.577109] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.626434] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 22s idle [ 774.675037] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.757289] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.840201] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.918292] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.966141] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.010551] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.070306] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.119838] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.202095] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.277793] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.359312] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.440463] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.534492] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.588983] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.691791] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.734557] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.790118] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.835683] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.875187] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.942860] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.987684] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.058272] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.102618] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.145537] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.209635] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.262911] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.336779] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.405934] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.450794] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.515027] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.564660] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.661482] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.745145] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.872109] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.966049] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.068589] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.134512] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.206046] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.309864] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.360966] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.425771] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.498474] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.553992] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.606046] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.651823] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.707314] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.775250] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.845619] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 785.277330] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 04:26:04 (1761207964) [ 785.912167] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 785.975251] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.197678] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.265675] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.370374] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.404414] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.467131] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.693423] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.745107] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.872725] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.896535] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 786.954357] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.120751] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.175384] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.289440] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.440392] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.627712] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.717215] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.749825] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.901473] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 787.943536] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.041292] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.236089] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.393194] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.453887] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.558676] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.630531] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.873467] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 788.969122] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.072228] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.175805] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.266477] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.426697] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.536830] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.723962] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 789.897536] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.064308] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.122089] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.186246] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.302215] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.439599] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.459384] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.595463] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.633147] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.713064] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.881253] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.029056] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.073326] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.214690] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.344738] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.509218] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.582919] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.639697] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.790193] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.888079] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.027374] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.122800] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.230822] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.331478] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.520991] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.687841] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.731803] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.920794] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.966188] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.155746] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.368780] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.479867] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.553525] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.589294] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.655464] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.700545] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.769817] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.795036] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.864301] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.104224] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.141184] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.255528] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.413792] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.592852] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.745138] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.921297] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.036627] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.145787] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.229313] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.319962] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.518887] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.676433] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.829076] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.043787] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.193617] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.267408] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.394092] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.492923] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.609782] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.723286] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.873949] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.989220] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.210227] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.312527] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.418662] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.536520] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.805835] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.880200] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.951171] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.981838] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.187836] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.269634] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.307241] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.472030] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.567645] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.648544] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.962645] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.993716] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.041658] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.149129] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.405765] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.568467] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.631569] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.712705] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.923393] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.067291] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.227711] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.228629] Lustre: lustre-OST0001-osc-ffff9d5ac7d1c800: disconnect after 23s idle [ 800.259833] Lustre: Skipped 1 previous similar message [ 800.334933] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.480170] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.599420] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.693364] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.797939] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.974071] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.033363] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.109444] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.242149] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.403145] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.467476] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.687659] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.803326] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.899711] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.981215] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.087567] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.200490] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.227675] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.307439] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.397818] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.481069] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.551179] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.616911] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.726145] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.791618] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.941865] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.130475] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.259424] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.319349] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.486744] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.559769] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.669146] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.897422] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.987283] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.065676] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.184167] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.312627] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.399218] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.584621] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.763784] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.867464] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.998361] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.193159] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.241899] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.271740] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.411117] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 811.717541] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 04:26:31 (1761207991) [ 817.668700] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 04:26:37 (1761207997) [ 824.514123] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 04:26:43 (1761208003) [ 859.962731] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 04:27:19 (1761208039) [ 860.488744] bash (32522): drop_caches: 3 [ 863.706514] bash (32522): drop_caches: 3 [ 867.068196] bash (32522): drop_caches: 3 [ 870.349443] bash (32522): drop_caches: 3 [ 873.522054] bash (32522): drop_caches: 3 [ 876.657271] bash (32522): drop_caches: 3 [ 879.813390] bash (32522): drop_caches: 3 [ 882.959937] bash (32522): drop_caches: 3 [ 886.090672] bash (32522): drop_caches: 3 [ 889.216274] bash (32522): drop_caches: 3 [ 892.388238] bash (32522): drop_caches: 3 [ 895.506702] bash (32522): drop_caches: 3 [ 898.636909] bash (32522): drop_caches: 3 [ 901.802115] bash (32522): drop_caches: 3 [ 906.213262] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 04:28:05 (1761208085) [ 913.425855] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 04:28:13 (1761208093) [ 952.435608] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 04:28:51 (1761208131) [ 956.263054] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 958.151310] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 04:28:57 (1761208137) [ 958.946283] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 20s idle [ 958.949195] Lustre: Skipped 1 previous similar message [ 965.768916] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 04:29:05 (1761208145) [ 972.198356] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 04:29:11 (1761208151) [ 994.786875] Lustre: lustre-OST0000-osc-ffff9d5ac659f800: disconnect after 21s idle [ 994.789872] Lustre: Skipped 3 previous similar messages [ 1040.488929] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 04:30:20 (1761208220) [ 1046.147867] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 04:30:25 (1761208225) [ 1051.831957] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 04:30:31 (1761208231) [ 1057.540474] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 04:30:37 (1761208237) [ 1058.792703] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1060.084562] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 04:30:39 (1761208239) [ 1065.631188] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 04:30:45 (1761208245) [ 1066.464946] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 20s idle [ 1072.860160] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 04:30:52 (1761208252) [ 1079.041312] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1080.281914] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 04:31:00 (1761208260) [ 1087.356188] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 04:31:07 (1761208267) [ 1087.662736] Lustre: *** cfs_fail_loc=314, val=0*** [ 1088.736254] Lustre: *** cfs_fail_loc=314, val=0*** [ 1088.745550] Lustre: Skipped 2 previous similar messages [ 1093.501546] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 04:31:13 (1761208273) [ 1105.226260] Lustre: *** cfs_fail_loc=314, val=0*** [ 1105.264234] LustreError: lustre-OST0000-osc-ffff9d5ac7d1c800: operation ldlm_enqueue to node 192.168.202.133@tcp failed: rc = -107 [ 1105.268498] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1105.280476] LustreError: lustre-OST0000-osc-ffff9d5ac7d1c800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1105.287750] LustreError: 41765:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9d5ac7d1c800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9d5ad4372600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1105.307520] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 1110.232396] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 04:31:30 (1761208290) [ 1110.496566] LustreError: 42347:0:(file.c:765:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1113.536159] LustreError: 42347:0:(file.c:765:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1118.289297] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 04:31:38 (1761208298) [ 1123.525358] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 04:31:43 (1761208303) [ 1129.844912] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1131.467742] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 04:31:51 (1761208311) [ 1132.897636] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1134.200797] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 04:31:54 (1761208314) [ 1135.284808] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1136.478699] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 04:31:56 (1761208316) [ 1137.547401] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1138.813402] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 04:31:58 (1761208318) [ 1139.938266] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1141.220489] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 04:32:01 (1761208321) [ 1193.367682] Lustre: lustre-OST0000-osc-ffff9d5ac659f800: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1193.393249] LustreError: lustre-OST0000-osc-ffff9d5ac659f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1193.399497] Lustre: lustre-OST0000-osc-ffff9d5ac659f800: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 1193.460700] LustreError: lustre-OST0000-osc-ffff9d5ac7d1c800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1214.946774] Lustre: lustre-OST0000-osc-ffff9d5ac659f800: disconnect after 21s idle [ 1214.957329] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: Connection to lustre-OST0001 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1214.985249] Lustre: Skipped 1 previous similar message [ 1215.011122] LustreError: lustre-OST0001-osc-ffff9d5ac659f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1215.029579] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 1215.039139] Lustre: Skipped 1 previous similar message [ 1227.970803] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1229.063783] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid in IDLE state after 0 sec [ 1231.474541] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1232.677637] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid in FULL state after 0 sec [ 1236.492696] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1237.557714] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid in IDLE state after 0 sec [ 1240.047762] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1241.217186] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid in IDLE state after 0 sec [ 1248.189460] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1249.397615] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5ac659f800.ost_server_uuid in IDLE state after 0 sec [ 1252.185261] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid 50 [ 1253.322733] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d5ac659f800.ost_server_uuid in IDLE state after 0 sec [ 1254.603537] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 04:33:54 (1761208434) [ 1256.510743] Lustre: DEBUG MARKER: Race attempt 0 [ 1259.064597] Lustre: DEBUG MARKER: Wait for 49048 49134 for 60 sec... [ 1326.791361] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 04:35:06 (1761208506) [ 1333.779489] Lustre: DEBUG MARKER: start test - cycle (0) [ 1357.445635] Lustre: DEBUG MARKER: start test - cycle (1) [ 1383.011334] Lustre: DEBUG MARKER: start test - cycle (2) [ 1408.922457] Lustre: DEBUG MARKER: start test - cycle (3) [ 1432.994994] Lustre: DEBUG MARKER: start test - cycle (4) [ 1459.658615] Lustre: DEBUG MARKER: start test - cycle (5) [ 1485.310531] Lustre: DEBUG MARKER: start test - cycle (6) [ 1486.306510] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 21s idle [ 1486.309787] Lustre: Skipped 5 previous similar messages [ 1511.271945] Lustre: DEBUG MARKER: start test - cycle (7) [ 1536.146843] Lustre: DEBUG MARKER: start test - cycle (8) [ 1561.611601] Lustre: DEBUG MARKER: start test - cycle (9) [ 1585.969654] Lustre: DEBUG MARKER: start test - cycle (10) [ 1611.424167] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 04:39:51 (1761208791) [ 1693.864166] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 04:41:13 (1761208873) [ 1699.757209] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 04:41:19 (1761208879) [ 1706.919576] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 04:41:26 (1761208886) [ 1712.961647] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 04:41:32 (1761208892) [ 1713.195024] Lustre: *** cfs_fail_loc=411, val=0*** [ 1717.505787] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1718.805212] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 04:41:38 (1761208898) [ 1733.051453] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 04:41:52 (1761208912) [ 1746.983412] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 04:42:06 (1761208926) [ 1760.722850] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 04:42:20 (1761208940) [ 1775.856588] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 04:42:35 (1761208955) [ 1787.632976] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 04:42:47 (1761208967) [ 1798.944295] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 04:42:58 (1761208978) [ 1807.810291] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 04:43:07 (1761208987) [ 1818.431407] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 04:43:18 (1761208998) [ 1828.282476] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 04:43:27 (1761209007) [ 1840.393325] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 04:43:39 (1761209019) [ 1852.077417] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 04:43:51 (1761209031) [ 1864.364728] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 04:44:04 (1761209044) [ 2484.704234] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 24s idle [ 2484.707391] Lustre: Skipped 14 previous similar messages [ 2829.537948] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 05:00:09 (1761210009) [ 2839.087671] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 05:00:19 (1761210019) [ 2849.219418] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 05:00:29 (1761210029) [ 2859.179282] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 05:00:39 (1761210039) [ 2868.213389] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 05:00:48 (1761210048) [ 2877.241364] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 05:00:57 (1761210057) [ 2886.029761] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 05:01:06 (1761210066) [ 2894.557042] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 05:01:14 (1761210074) [ 2904.290792] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 05:01:23 (1761210083) [ 2961.413758] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 05:02:21 (1761210141) [ 2971.877834] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 05:02:31 (1761210151) [ 2981.297247] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 05:02:41 (1761210161) [ 2990.713215] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 05:02:50 (1761210170) [ 3001.122841] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 05:03:00 (1761210180) [ 3011.836212] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 05:03:11 (1761210191) [ 3021.384699] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 05:03:21 (1761210201) [ 3031.213704] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 05:03:30 (1761210210) [ 3032.570596] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3033.777915] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 05:03:33 (1761210213) [ 3142.302430] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 05:05:22 (1761210322) [ 3186.144414] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: disconnect after 20s idle [ 3186.149977] Lustre: Skipped 6 previous similar messages [ 4096.110915] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 05:21:16 (1761211276) [ 4102.624179] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 20s idle [ 4102.627103] Lustre: Skipped 7 previous similar messages [ 4106.136418] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 05:21:25 (1761211285) [ 4117.119869] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 05:21:37 (1761211297) [ 4127.940957] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 05:21:47 (1761211307) [ 4138.065692] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 05:21:57 (1761211317) [ 4146.998619] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 05:22:06 (1761211326) [ 4156.557798] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 05:22:16 (1761211336) [ 4166.257935] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 05:22:26 (1761211346) [ 4176.492601] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 05:22:36 (1761211356) [ 4177.757605] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4179.445018] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 05:22:39 (1761211359) [ 4284.147985] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 05:24:24 (1761211464) [ 4293.608119] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 05:24:33 (1761211473) [ 4304.421295] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 05:24:44 (1761211484) [ 4316.163564] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 05:24:55 (1761211495) [ 4326.203491] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 05:25:05 (1761211505) [ 4337.934395] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 05:25:17 (1761211517) [ 4350.525648] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 05:25:30 (1761211530) [ 4361.303979] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 05:25:41 (1761211541) [ 4362.417735] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4363.712540] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 05:25:43 (1761211543) [ 4722.146918] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: disconnect after 25s idle [ 4722.149533] Lustre: Skipped 7 previous similar messages [ 5425.796638] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 05:43:25 (1761212605) [ 5435.221089] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 05:43:35 (1761212615) [ 5444.082466] Lustre: lustre-OST0001-osc-ffff9d5ac7d1c800: disconnect after 23s idle [ 5444.085129] Lustre: Skipped 3 previous similar messages [ 5446.774914] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 05:43:46 (1761212626) [ 5459.050688] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 05:43:58 (1761212638) [ 5471.623260] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 05:44:11 (1761212651) [ 5483.809310] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 05:44:23 (1761212663) [ 5497.088858] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 05:44:36 (1761212676) [ 5509.804888] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 05:44:49 (1761212689) [ 5520.909464] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 05:45:00 (1761212700) [ 5522.064185] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5523.612262] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 05:45:03 (1761212703) [ 5524.862161] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5526.238922] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 05:45:06 (1761212706) [ 5527.492875] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5529.091568] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 05:45:08 (1761212708) [ 5530.259896] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5531.763056] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 05:45:11 (1761212711) [ 5532.950208] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5534.514291] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 05:45:14 (1761212714) [ 5535.917135] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5537.316388] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 05:45:17 (1761212717) [ 5538.500570] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5539.808929] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 05:45:19 (1761212719) [ 5541.020993] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5542.807473] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 05:45:22 (1761212722) [ 5543.180409] LustreError: 9520:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5545.282100] LustreError: 9520:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5555.282596] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 05:45:34 (1761212734) [ 5564.969481] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 05:45:44 (1761212744) [ 5565.724553] LustreError: 260835:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5569.792164] LustreError: 260835:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5569.832245] LustreError: 260835:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5573.905923] LustreError: 260835:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5573.973099] LustreError: 260841:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5578.038106] LustreError: 260841:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5583.474525] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 05:46:03 (1761212763) [ 5594.236985] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 05:46:13 (1761212773) [ 5600.935823] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 05:46:20 (1761212780) [ 5607.828970] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 05:46:27 (1761212787) [ 5636.722763] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 05:46:56 (1761212816) [ 5646.742888] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 05:47:06 (1761212826) [ 5656.918498] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 05:47:16 (1761212836) [ 5674.040644] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 05:47:33 (1761212853) [ 5688.549457] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 05:47:47 (1761212867) [ 5689.987508] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5691.598165] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 05:47:51 (1761212871) [ 5710.291934] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 05:48:09 (1761212889) [ 5732.090600] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 05:48:31 (1761212911) [ 5820.536066] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 05:50:00 (1761213000) [ 5908.194920] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 05:51:28 (1761213088) [ 5914.257728] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5920.762578] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 05:51:40 (1761213100) [ 5926.704405] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 05:51:46 (1761213106) [ 5927.944112] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5929.250748] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 05:51:49 (1761213109) [ 5930.839381] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5932.313516] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 05:51:52 (1761213112) [ 5933.492316] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 5934.865816] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 05:51:54 (1761213114) [ 5936.526662] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 5938.272619] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 05:51:57 (1761213117) [ 5944.274374] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 05:52:03 (1761213123) [ 5950.980248] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 05:52:10 (1761213130) [ 5954.131043] LustreError: lustre-MDT0000-mdc-ffff9d5ac7d1c800: operation ldlm_enqueue to node 192.168.202.133@tcp failed: rc = -35 [ 5961.653915] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 05:52:21 (1761213141) [ 5962.312146] LustreError: 2370:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5964.400173] LustreError: 2370:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5975.501032] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 05:52:34 (1761213154) [ 6033.501412] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 05:53:32 (1761213212) [ 6042.725724] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 05:53:42 (1761213222) [ 6048.225186] Lustre: lustre-OST0000-osc-ffff9d5ac7d1c800: disconnect after 20s idle [ 6048.228929] Lustre: Skipped 7 previous similar messages [ 6056.319993] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 05:53:55 (1761213235) [ 6072.145938] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 05:54:11 (1761213251) [ 6086.246272] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 05:54:25 (1761213265) [ 6108.516105] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 05:54:48 (1761213288) [ 6126.391237] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 05:55:06 (1761213306) [ 6135.035392] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 05:55:14 (1761213314) [ 6144.651443] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 05:55:24 (1761213324) [ 6169.403670] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 05:55:48 (1761213348) [ 6229.242594] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 05:56:48 (1761213408) [ 6378.794665] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 05:59:18 (1761213558) [ 6524.996954] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 06:01:44 (1761213704) [ 6672.864270] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: disconnect after 23s idle [ 6672.866760] Lustre: Skipped 18 previous similar messages [ 6930.220754] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 06:08:30 (1761214110) [ 6975.995833] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 06:09:15 (1761214155) [ 7022.460124] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 06:10:02 (1761214202) [ 7062.147183] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 06:10:42 (1761214242) [ 7152.292582] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 06:12:12 (1761214332) [ 7222.266576] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 06:13:21 (1761214401) [ 7292.385232] Lustre: lustre-OST0001-osc-ffff9d5ac659f800: disconnect after 21s idle [ 7292.391404] Lustre: Skipped 16 previous similar messages [ 7350.130865] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 06:15:30 (1761214530) [ 7356.085948] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 06:15:36 (1761214536) [ 7411.209607] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 06:16:31 (1761214591) [ 7495.390978] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 06:17:55 (1761214675) [ 7508.406290] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 06:18:07 (1761214687) [ 7602.906894] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 06:19:42 (1761214782) [ 7628.436953] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 06:20:08 (1761214808) [ 7678.258132] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 06:20:57 (1761214857) [ 7685.273688] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 06:21:05 (1761214865) [ 7702.530805] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 06:21:22 (1761214882) [ 7703.902336] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 7705.272531] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 06:21:24 (1761214884) [ 7706.562881] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 7708.256836] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 06:21:27 (1761214887) [ 7709.653779] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 7710.987694] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 06:21:30 (1761214890) [ 7712.299821] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 7713.698212] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 06:21:33 (1761214893) [ 7714.922846] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7716.187760] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 06:21:36 (1761214896) [ 7837.765956] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 06:23:36 (1761215016) [ 7845.747781] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 06:23:45 (1761215025) [ 7847.671381] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 7849.721457] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 06:23:49 (1761215029) [ 7861.799493] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 06:24:01 (1761215041) [ 7872.273172] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 06:24:11 (1761215051) [ 7873.544204] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 7874.809167] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 06:24:14 (1761215054) [ 7875.956917] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 7877.545995] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 06:24:17 (1761215057) [ 7878.979509] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 7880.579801] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 06:24:20 (1761215060) [ 7895.626416] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 06:24:35 (1761215075) [ 7896.075334] Lustre: DEBUG MARKER: write [ 7896.127335] LustreError: 14447:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7898.131916] Lustre: DEBUG MARKER: kill 323189 [ 7898.148075] LustreError: 323189:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7901.138605] LustreError: 14447:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7904.216138] LustreError: 323189:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7910.111366] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 06:24:49 (1761215089) [ 7913.012900] LustreError: 323796:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7923.048144] LustreError: 323796:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7929.962873] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 06:25:09 (1761215109) [ 7930.431897] LustreError: 324376:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7932.528092] LustreError: 324376:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 7942.712224] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 06:25:22 (1761215122) [ 7944.275519] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7946.029737] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 06:25:25 (1761215125) [ 7952.806222] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 06:25:32 (1761215132) [ 7959.944644] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 06:25:39 (1761215139) [ 7966.903802] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 06:25:46 (1761215146) [ 7972.809601] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 06:25:52 (1761215152) [ 7979.060208] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 06:25:58 (1761215158) [ 7986.630917] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 06:26:05 (1761215165) [ 7994.285104] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 7995.605856] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 06:26:15 (1761215175) [ 7997.102133] Lustre: *** cfs_fail_loc=415, val=0*** [ 8008.466961] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 06:26:27 (1761215187) [ 8009.839115] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 8011.250555] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 06:26:31 (1761215191) [ 8011.745681] LustreError: 31923:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8011.752672] LustreError: 31923:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 8016.771385] LustreError: 31923:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8026.960135] LustreError: 31924:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8026.970583] LustreError: 31924:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 8038.068898] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 06:26:57 (1761215217) [ 8039.449838] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 8041.099432] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 06:27:00 (1761215220) [ 8046.620577] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 06:27:06 (1761215226) [ 8051.149194] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 06:27:11 (1761215231) [ 8058.294076] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 06:27:18 (1761215238) [ 8069.170327] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 06:27:29 (1761215249) [ 8069.743888] LustreError: 293297:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8069.747642] LustreError: 293297:0:(osc_request.c:2978:osc_build_rpc()) Skipped 5 previous similar messages [ 8073.808230] LustreError: 293297:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8073.817124] LustreError: 293297:0:(osc_request.c:2978:osc_build_rpc()) Skipped 2 previous similar messages [ 8079.928356] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 06:27:39 (1761215259) [ 8082.584577] LustreError: 334331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac659f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8082.611477] LustreError: 334331:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8082.651115] Lustre: Unmounted lustre-client [ 8084.653512] LustreError: 334352:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8084.662672] LustreError: 334352:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8084.676895] LustreError: 334352:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8084.684841] LustreError: 334352:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 8084.720510] Lustre: Unmounted lustre-client [ 8086.169786] Lustre: DEBUG MARKER: Iteration 1 [ 8086.603297] LustreError: 334510:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8086.604088] LustreError: 334509:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8086.614812] LustreError: 334510:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8086.831037] Lustre: Mounted lustre-client [ 8088.050636] LustreError: 334619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad420d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8088.057397] LustreError: 334619:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8088.070776] LustreError: 334619:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8088.072833] LustreError: 334619:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8088.105125] Lustre: Unmounted lustre-client [ 8090.525903] Key type lgssc unregistered [ 8090.809082] LNet: 334863:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8090.833344] LNetError: 334863:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8090.858979] LNet: Removed LNI 192.168.202.33@tcp [ 8091.678266] Key type .llcrypt unregistered [ 8091.681095] Key type ._llcrypt unregistered [ 8092.169669] Key type ._llcrypt registered [ 8092.175623] Key type .llcrypt registered [ 8092.897853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8092.906678] alg: No test for adler32 (adler32-zlib) [ 8094.493262] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8095.466993] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8097.467287] Key type lgssc registered [ 8100.004652] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8111.460235] Lustre: DEBUG MARKER: Iteration 2 [ 8111.843716] LustreError: 335682:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8111.846457] LustreError: 335681:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8111.861030] LustreError: 335682:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8112.624062] Lustre: Mounted lustre-client [ 8113.964359] LustreError: 335791:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7138800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8113.981456] LustreError: 335791:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8114.012109] Lustre: Unmounted lustre-client [ 8116.535356] Key type lgssc unregistered [ 8116.755935] LNet: 336033:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8116.759524] LNetError: 336033:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8116.773956] LNet: Removed LNI 192.168.202.33@tcp [ 8117.332265] Key type .llcrypt unregistered [ 8117.333457] Key type ._llcrypt unregistered [ 8117.813857] Key type ._llcrypt registered [ 8117.815521] Key type .llcrypt registered [ 8118.175363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8118.195466] alg: No test for adler32 (adler32-zlib) [ 8119.357771] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8119.611853] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8121.288169] Key type lgssc registered [ 8122.357660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8131.366136] Lustre: DEBUG MARKER: Iteration 3 [ 8131.616128] LustreError: 336846:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8131.619603] LustreError: 336855:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8131.625498] LustreError: 336846:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8132.398729] Lustre: Mounted lustre-client [ 8133.926816] LustreError: 336959:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8133.942337] LustreError: 336966:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8133.954769] LustreError: 336959:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8133.980270] Lustre: Unmounted lustre-client [ 8136.742728] Key type lgssc unregistered [ 8136.994773] LNet: 337206:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8137.009657] LNetError: 337206:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8138.091084] LNet: Removed LNI 192.168.202.33@tcp [ 8138.676171] Key type .llcrypt unregistered [ 8138.677820] Key type ._llcrypt unregistered [ 8139.256939] Key type ._llcrypt registered [ 8139.275607] Key type .llcrypt registered [ 8139.827494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8139.845283] alg: No test for adler32 (adler32-zlib) [ 8140.861887] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8141.055251] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8142.704739] Key type lgssc registered [ 8143.869101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8154.336611] Lustre: DEBUG MARKER: Iteration 4 [ 8154.560099] LustreError: 338021:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8154.560403] LustreError: 338028:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8154.572600] LustreError: 338021:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8155.311904] Lustre: Mounted lustre-client [ 8156.775455] LustreError: 338135:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aca088800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8156.796587] LustreError: 338135:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8156.841081] Lustre: Unmounted lustre-client [ 8160.012775] Key type lgssc unregistered [ 8160.265449] LNet: 338375:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8160.310899] LNetError: 338375:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8160.327696] LNet: Removed LNI 192.168.202.33@tcp [ 8161.140139] Key type .llcrypt unregistered [ 8161.148053] Key type ._llcrypt unregistered [ 8162.282072] Key type ._llcrypt registered [ 8162.284365] Key type .llcrypt registered [ 8162.849520] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8162.861471] alg: No test for adler32 (adler32-zlib) [ 8163.950319] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8164.194202] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8165.944154] Key type lgssc registered [ 8167.031435] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8179.264344] Lustre: DEBUG MARKER: Iteration 5 [ 8179.674341] LustreError: 339195:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8179.675949] LustreError: 339196:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8179.734082] LustreError: 339195:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4964 [ 8180.472247] Lustre: Mounted lustre-client [ 8181.771214] LustreError: 339308:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7de1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8181.784301] LustreError: 339308:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8181.789067] LustreError: 339308:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8181.837950] Lustre: Unmounted lustre-client [ 8184.697789] Key type lgssc unregistered [ 8184.939383] LNet: 339550:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8184.949626] LNetError: 339550:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8184.973967] LNet: Removed LNI 192.168.202.33@tcp [ 8185.632232] Key type .llcrypt unregistered [ 8185.635923] Key type ._llcrypt unregistered [ 8186.375658] Key type ._llcrypt registered [ 8186.379540] Key type .llcrypt registered [ 8186.890980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8186.901585] alg: No test for adler32 (adler32-zlib) [ 8188.066360] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8188.315074] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8189.993105] Key type lgssc registered [ 8191.179704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8201.192420] Lustre: DEBUG MARKER: Iteration 6 [ 8201.525516] LustreError: 340370:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8201.526104] LustreError: 340371:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8201.539052] LustreError: 340370:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8202.252609] Lustre: Mounted lustre-client [ 8203.479416] LustreError: 340471:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aedfc7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8203.538578] LustreError: 340471:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8203.581675] Lustre: Unmounted lustre-client [ 8206.193920] Key type lgssc unregistered [ 8206.512622] LNet: 340717:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8206.522929] LNetError: 340717:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8206.543197] LNet: Removed LNI 192.168.202.33@tcp [ 8207.301149] Key type .llcrypt unregistered [ 8207.303363] Key type ._llcrypt unregistered [ 8208.046506] Key type ._llcrypt registered [ 8208.058452] Key type .llcrypt registered [ 8208.596461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8208.609553] alg: No test for adler32 (adler32-zlib) [ 8209.639797] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8209.854441] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8211.536185] Key type lgssc registered [ 8212.606376] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8223.157356] Lustre: DEBUG MARKER: Iteration 7 [ 8223.604727] LustreError: 341536:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8223.616062] LustreError: 341537:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8223.627526] LustreError: 341536:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8224.440144] Lustre: Mounted lustre-client [ 8226.022912] LustreError: 341645:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5b014a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8226.058297] LustreError: 341645:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8226.161992] Lustre: Unmounted lustre-client [ 8228.917110] Key type lgssc unregistered [ 8229.186584] LNet: 341887:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8229.198302] LNetError: 341887:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8229.220806] LNet: Removed LNI 192.168.202.33@tcp [ 8229.904134] Key type .llcrypt unregistered [ 8229.906083] Key type ._llcrypt unregistered [ 8230.966713] Key type ._llcrypt registered [ 8230.968294] Key type .llcrypt registered [ 8231.378827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8231.392879] alg: No test for adler32 (adler32-zlib) [ 8232.457095] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8232.718679] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8234.408158] Key type lgssc registered [ 8235.601555] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8244.634510] Lustre: DEBUG MARKER: Iteration 8 [ 8244.891926] LustreError: 342704:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8244.901081] LustreError: 342713:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8244.913844] LustreError: 342704:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8245.595314] Lustre: Mounted lustre-client [ 8246.717817] LustreError: 342819:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7de0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8246.726357] LustreError: 342819:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8246.737937] LustreError: 342820:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8246.784345] Lustre: Unmounted lustre-client [ 8249.209336] Key type lgssc unregistered [ 8249.516030] LNet: 343060:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8249.526479] LNetError: 343060:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8250.535464] LNet: Removed LNI 192.168.202.33@tcp [ 8251.197340] Key type .llcrypt unregistered [ 8251.199987] Key type ._llcrypt unregistered [ 8251.764227] Key type ._llcrypt registered [ 8251.765980] Key type .llcrypt registered [ 8252.263686] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8252.290980] alg: No test for adler32 (adler32-zlib) [ 8253.317693] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8253.559088] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8255.216181] Key type lgssc registered [ 8256.076516] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8264.639952] Lustre: DEBUG MARKER: Iteration 9 [ 8264.954363] LustreError: 343881:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8264.955074] LustreError: 343880:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8264.974852] LustreError: 343881:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8265.710778] Lustre: Mounted lustre-client [ 8266.802979] LustreError: 343986:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad420e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8266.821091] LustreError: 343986:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8266.827719] LustreError: 343986:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8266.846601] Lustre: Unmounted lustre-client [ 8268.912995] Key type lgssc unregistered [ 8269.098461] LNet: 344228:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8269.112148] LNetError: 344228:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8270.185366] LNet: Removed LNI 192.168.202.33@tcp [ 8270.830192] Key type .llcrypt unregistered [ 8270.831840] Key type ._llcrypt unregistered [ 8271.890522] Key type ._llcrypt registered [ 8271.892707] Key type .llcrypt registered [ 8272.051476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8272.066054] alg: No test for adler32 (adler32-zlib) [ 8273.126807] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8273.319830] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8275.000913] Key type lgssc registered [ 8276.275612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8288.065494] Lustre: DEBUG MARKER: Iteration 10 [ 8288.435533] LustreError: 345044:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8288.437241] LustreError: 345050:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8288.456703] LustreError: 345044:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 8289.289762] Lustre: Mounted lustre-client [ 8291.224512] LustreError: 345152:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af23cd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8291.245321] LustreError: 345152:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8291.255925] LustreError: 345150:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8291.263931] LustreError: 345150:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8291.289161] Lustre: Unmounted lustre-client [ 8294.277897] Key type lgssc unregistered [ 8294.501504] LNet: 345395:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8294.504637] LNetError: 345395:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8294.520547] LNet: Removed LNI 192.168.202.33@tcp [ 8295.095259] Key type .llcrypt unregistered [ 8295.100126] Key type ._llcrypt unregistered [ 8295.880881] Key type ._llcrypt registered [ 8295.884443] Key type .llcrypt registered [ 8296.358276] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8296.368540] alg: No test for adler32 (adler32-zlib) [ 8297.439350] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8297.663883] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8299.352161] Key type lgssc registered [ 8300.646215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8310.710211] Lustre: DEBUG MARKER: Iteration 11 [ 8311.034197] LustreError: 346214:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8311.039762] LustreError: 346215:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8311.047286] LustreError: 346214:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8311.727404] Lustre: Mounted lustre-client [ 8313.022365] LustreError: 346323:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af5d59800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8313.042694] LustreError: 346323:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8313.077796] Lustre: Unmounted lustre-client [ 8313.082050] Lustre: Skipped 1 previous similar message [ 8315.408795] Key type lgssc unregistered [ 8315.678672] LNet: 346567:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8315.688763] LNetError: 346567:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8315.709515] LNet: Removed LNI 192.168.202.33@tcp [ 8316.328264] Key type .llcrypt unregistered [ 8316.333571] Key type ._llcrypt unregistered [ 8317.064628] Key type ._llcrypt registered [ 8317.093039] Key type .llcrypt registered [ 8317.517608] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8317.531628] alg: No test for adler32 (adler32-zlib) [ 8318.704374] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8318.948969] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8320.658168] Key type lgssc registered [ 8321.988938] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8333.054705] Lustre: DEBUG MARKER: Iteration 12 [ 8333.327623] LustreError: 347385:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8333.336102] LustreError: 347386:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8333.340552] LustreError: 347385:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8335.195073] Lustre: Mounted lustre-client [ 8336.539321] LustreError: 347483:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af23cc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8336.551650] LustreError: 347483:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8336.579152] Lustre: Unmounted lustre-client [ 8339.041919] Key type lgssc unregistered [ 8339.341118] LNet: 347732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8339.351105] LNetError: 347732:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8340.393226] LNet: Removed LNI 192.168.202.33@tcp [ 8341.002104] Key type .llcrypt unregistered [ 8341.003596] Key type ._llcrypt unregistered [ 8341.764631] Key type ._llcrypt registered [ 8341.766276] Key type .llcrypt registered [ 8342.197257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8342.212607] alg: No test for adler32 (adler32-zlib) [ 8343.289860] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8343.519670] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8345.201165] Key type lgssc registered [ 8346.245706] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8356.049341] Lustre: DEBUG MARKER: Iteration 13 [ 8356.407759] LustreError: 348554:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8356.408090] LustreError: 348555:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8356.422619] LustreError: 348554:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8357.126690] Lustre: Mounted lustre-client [ 8358.283994] LustreError: 348657:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3ba6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8358.294713] LustreError: 348657:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8358.328248] Lustre: Unmounted lustre-client [ 8360.858876] Key type lgssc unregistered [ 8361.107233] LNet: 348902:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8361.118693] LNetError: 348902:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8361.141952] LNet: Removed LNI 192.168.202.33@tcp [ 8361.887405] Key type .llcrypt unregistered [ 8361.889504] Key type ._llcrypt unregistered [ 8362.631617] Key type ._llcrypt registered [ 8362.637884] Key type .llcrypt registered [ 8363.123748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8363.146469] alg: No test for adler32 (adler32-zlib) [ 8364.204915] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8364.414794] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8366.072236] Key type lgssc registered [ 8367.283101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8377.486471] Lustre: DEBUG MARKER: Iteration 14 [ 8377.855754] LustreError: 349720:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8377.860492] LustreError: 349721:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8377.864478] LustreError: 349720:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8378.589559] Lustre: Mounted lustre-client [ 8379.775446] LustreError: 349823:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac8457800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8379.792140] LustreError: 349823:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8379.827362] Lustre: Unmounted lustre-client [ 8382.529288] Key type lgssc unregistered [ 8382.795915] LNet: 350072:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8382.818488] LNetError: 350072:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8382.839622] LNet: Removed LNI 192.168.202.33@tcp [ 8383.561143] Key type .llcrypt unregistered [ 8383.563470] Key type ._llcrypt unregistered [ 8384.166588] Key type ._llcrypt registered [ 8384.174083] Key type .llcrypt registered [ 8384.663436] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8384.680390] alg: No test for adler32 (adler32-zlib) [ 8385.714587] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8385.962267] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8387.641520] Key type lgssc registered [ 8388.822349] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8398.353882] Lustre: DEBUG MARKER: Iteration 15 [ 8398.637452] LustreError: 350883:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8398.643267] LustreError: 350893:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8398.649541] LustreError: 350883:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8399.380330] Lustre: Mounted lustre-client [ 8400.295576] LustreError: 350993:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad420e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8400.312370] LustreError: 350993:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8400.344413] Lustre: Unmounted lustre-client [ 8402.394394] Key type lgssc unregistered [ 8402.586603] LNet: 351241:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8402.597462] LNetError: 351241:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8402.613237] LNet: Removed LNI 192.168.202.33@tcp [ 8403.126279] Key type .llcrypt unregistered [ 8403.128090] Key type ._llcrypt unregistered [ 8403.662873] Key type ._llcrypt registered [ 8403.666528] Key type .llcrypt registered [ 8404.061216] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8404.077432] alg: No test for adler32 (adler32-zlib) [ 8405.099645] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8405.286719] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8407.008177] Key type lgssc registered [ 8408.178781] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8418.384507] Lustre: DEBUG MARKER: Iteration 16 [ 8418.667699] LustreError: 352061:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8418.668161] LustreError: 352062:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8418.680569] LustreError: 352061:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8419.374855] Lustre: Mounted lustre-client [ 8420.786132] LustreError: 352165:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac788a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8420.805329] LustreError: 352165:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8420.842984] Lustre: Unmounted lustre-client [ 8423.207687] Key type lgssc unregistered [ 8423.503286] LNet: 352413:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8423.515088] LNetError: 352413:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8424.552220] LNet: Removed LNI 192.168.202.33@tcp [ 8425.248196] Key type .llcrypt unregistered [ 8425.252880] Key type ._llcrypt unregistered [ 8426.391069] Key type ._llcrypt registered [ 8426.395353] Key type .llcrypt registered [ 8426.729583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8426.751649] alg: No test for adler32 (adler32-zlib) [ 8427.938556] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8428.257441] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8430.005516] Key type lgssc registered [ 8431.697944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8443.974275] Lustre: DEBUG MARKER: Iteration 17 [ 8444.345257] LustreError: 353234:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8444.355982] LustreError: 353233:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8444.358750] LustreError: 353234:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 8445.151418] Lustre: Mounted lustre-client [ 8445.157928] Lustre: Skipped 1 previous similar message [ 8447.026890] LustreError: 353341:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aeafd5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8447.068588] LustreError: 353342:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8447.121925] Lustre: Unmounted lustre-client [ 8449.994888] Key type lgssc unregistered [ 8450.300159] LNet: 353583:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8450.312137] LNetError: 353583:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8450.330501] LNet: Removed LNI 192.168.202.33@tcp [ 8451.029294] Key type .llcrypt unregistered [ 8451.033402] Key type ._llcrypt unregistered [ 8451.981155] Key type ._llcrypt registered [ 8451.982846] Key type .llcrypt registered [ 8452.230484] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8452.256391] alg: No test for adler32 (adler32-zlib) [ 8453.232752] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8453.477793] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8455.208374] Key type lgssc registered [ 8456.434939] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8466.486283] Lustre: DEBUG MARKER: Iteration 18 [ 8466.736573] LustreError: 354401:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8466.740609] LustreError: 354403:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8466.744990] LustreError: 354401:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8467.464107] Lustre: Mounted lustre-client [ 8468.915940] LustreError: 354509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d19000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8468.954912] LustreError: 354511:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8468.962661] LustreError: 354511:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8469.004799] Lustre: Unmounted lustre-client [ 8471.666896] Key type lgssc unregistered [ 8471.957611] LNet: 354752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8471.965826] LNetError: 354752:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8472.006175] LNet: Removed LNI 192.168.202.33@tcp [ 8472.603137] Key type .llcrypt unregistered [ 8472.604939] Key type ._llcrypt unregistered [ 8473.525223] Key type ._llcrypt registered [ 8473.526740] Key type .llcrypt registered [ 8473.749146] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8473.774420] alg: No test for adler32 (adler32-zlib) [ 8474.891373] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8475.139364] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8476.896123] Key type lgssc registered [ 8478.132456] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8489.569824] Lustre: DEBUG MARKER: Iteration 19 [ 8489.876586] LustreError: 355572:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8489.880560] LustreError: 355573:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8489.903397] LustreError: 355572:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4980 [ 8490.650958] Lustre: Mounted lustre-client [ 8492.691057] LustreError: 355686:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3b54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8492.722484] LustreError: 355686:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8492.772074] Lustre: Unmounted lustre-client [ 8495.707783] Key type lgssc unregistered [ 8496.050560] LNet: 355924:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8496.063069] LNetError: 355924:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8496.100633] LNet: Removed LNI 192.168.202.33@tcp [ 8496.951400] Key type .llcrypt unregistered [ 8496.953299] Key type ._llcrypt unregistered [ 8497.746819] Key type ._llcrypt registered [ 8497.748605] Key type .llcrypt registered [ 8498.139677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8498.155083] alg: No test for adler32 (adler32-zlib) [ 8499.359844] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8499.723396] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8501.504215] Key type lgssc registered [ 8503.132490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8513.117252] Lustre: DEBUG MARKER: Iteration 20 [ 8513.463318] LustreError: 356744:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8513.463350] LustreError: 356743:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8513.484385] LustreError: 356744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8514.289437] Lustre: Mounted lustre-client [ 8515.837274] LustreError: 356847:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7062000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8515.865788] LustreError: 356847:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8515.913190] Lustre: Unmounted lustre-client [ 8518.508263] Key type lgssc unregistered [ 8518.889842] LNet: 357095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8518.898779] LNetError: 357095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8518.922740] LNet: Removed LNI 192.168.202.33@tcp [ 8519.898200] Key type .llcrypt unregistered [ 8519.900943] Key type ._llcrypt unregistered [ 8521.289386] Key type ._llcrypt registered [ 8521.291020] Key type .llcrypt registered [ 8521.547197] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8521.563704] alg: No test for adler32 (adler32-zlib) [ 8522.635214] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8522.863189] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8524.536115] Key type lgssc registered [ 8525.944506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8537.133509] Lustre: DEBUG MARKER: Iteration 21 [ 8537.506300] LustreError: 357914:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8537.516742] LustreError: 357924:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8537.524015] LustreError: 357914:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8538.249352] Lustre: Mounted lustre-client [ 8539.645096] LustreError: 358024:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad4209800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8539.665604] LustreError: 358025:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8539.672944] LustreError: 358025:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8539.708626] Lustre: Unmounted lustre-client [ 8542.381671] Key type lgssc unregistered [ 8542.645915] LNet: 358264:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8542.649470] LNetError: 358264:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8542.688766] LNet: Removed LNI 192.168.202.33@tcp [ 8543.281160] Key type .llcrypt unregistered [ 8543.282510] Key type ._llcrypt unregistered [ 8543.903094] Key type ._llcrypt registered [ 8543.904630] Key type .llcrypt registered [ 8544.304466] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8544.317454] alg: No test for adler32 (adler32-zlib) [ 8545.355615] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8545.547056] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8547.224198] Key type lgssc registered [ 8548.252495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8557.737452] Lustre: DEBUG MARKER: Iteration 22 [ 8558.149582] LustreError: 359082:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8558.153125] LustreError: 359084:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8558.164423] LustreError: 359082:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 8558.858901] Lustre: Mounted lustre-client [ 8560.753014] LustreError: 359185:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aeaec0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8560.823325] LustreError: 359185:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8560.862063] Lustre: Unmounted lustre-client [ 8563.441347] Key type lgssc unregistered [ 8563.695679] LNet: 359427:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8563.699670] LNetError: 359427:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8563.712301] LNet: Removed LNI 192.168.202.33@tcp [ 8564.341167] Key type .llcrypt unregistered [ 8564.343934] Key type ._llcrypt unregistered [ 8565.027549] Key type ._llcrypt registered [ 8565.029182] Key type .llcrypt registered [ 8565.370981] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8565.393380] alg: No test for adler32 (adler32-zlib) [ 8566.494777] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8566.775288] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8568.472110] Key type lgssc registered [ 8569.645343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8579.391296] Lustre: DEBUG MARKER: Iteration 23 [ 8579.765235] LustreError: 360246:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8579.770339] LustreError: 360247:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8579.777426] LustreError: 360246:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 8580.541723] Lustre: Mounted lustre-client [ 8581.877597] LustreError: 360352:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af6a3d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8581.898972] LustreError: 360352:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8581.910816] LustreError: 360352:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8581.931563] Lustre: Unmounted lustre-client [ 8584.111226] Key type lgssc unregistered [ 8584.400473] LNet: 360596:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8584.412166] LNetError: 360596:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8584.430456] LNet: Removed LNI 192.168.202.33@tcp [ 8585.008180] Key type .llcrypt unregistered [ 8585.013083] Key type ._llcrypt unregistered [ 8585.791908] Key type ._llcrypt registered [ 8585.793662] Key type .llcrypt registered [ 8586.002882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8586.014439] alg: No test for adler32 (adler32-zlib) [ 8587.041472] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8587.292821] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8588.920766] Key type lgssc registered [ 8589.843485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8597.863811] Lustre: DEBUG MARKER: Iteration 24 [ 8598.115885] LustreError: 361412:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8598.118750] LustreError: 361411:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8598.124476] LustreError: 361412:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 8598.822927] Lustre: Mounted lustre-client [ 8599.868478] LustreError: 361521:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af5d59800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8599.900148] LustreError: 361521:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8599.904020] LustreError: 361521:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8599.939256] Lustre: Unmounted lustre-client [ 8601.988459] Key type lgssc unregistered [ 8602.179808] LNet: 361762:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8602.184095] LNetError: 361762:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8602.192942] LNet: Removed LNI 192.168.202.33@tcp [ 8602.655167] Key type .llcrypt unregistered [ 8602.657521] Key type ._llcrypt unregistered [ 8603.122763] Key type ._llcrypt registered [ 8603.124210] Key type .llcrypt registered [ 8603.438657] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8603.449761] alg: No test for adler32 (adler32-zlib) [ 8604.469727] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8604.631570] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8606.272640] Key type lgssc registered [ 8607.310444] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8619.539935] Lustre: DEBUG MARKER: Iteration 25 [ 8619.790763] LustreError: 362576:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8619.798344] LustreError: 362591:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8619.803535] LustreError: 362576:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8620.557632] Lustre: Mounted lustre-client [ 8622.678439] LustreError: 362687:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac788b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8622.691680] LustreError: 362686:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8622.701457] LustreError: 362687:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8622.789585] Lustre: Unmounted lustre-client [ 8626.248534] Key type lgssc unregistered [ 8626.585767] LNet: 362927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8626.602600] LNetError: 362927:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8626.629404] LNet: Removed LNI 192.168.202.33@tcp [ 8627.360146] Key type .llcrypt unregistered [ 8627.369081] Key type ._llcrypt unregistered [ 8628.229225] Key type ._llcrypt registered [ 8628.230653] Key type .llcrypt registered [ 8629.030917] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8629.045183] alg: No test for adler32 (adler32-zlib) [ 8630.225787] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8630.479507] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8632.136245] Key type lgssc registered [ 8633.342911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8643.560384] Lustre: DEBUG MARKER: Iteration 26 [ 8644.054607] LustreError: 363744:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8644.069749] LustreError: 363755:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8644.074027] LustreError: 363744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [ 8644.813178] Lustre: Mounted lustre-client [ 8645.815665] LustreError: 363849:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3277000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8645.841891] LustreError: 363849:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8645.877322] Lustre: Unmounted lustre-client [ 8648.562685] Key type lgssc unregistered [ 8648.776392] LNet: 364098:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8648.782693] LNetError: 364098:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8648.803849] LNet: Removed LNI 192.168.202.33@tcp [ 8649.276156] Key type .llcrypt unregistered [ 8649.285815] Key type ._llcrypt unregistered [ 8649.728583] Key type ._llcrypt registered [ 8649.729946] Key type .llcrypt registered [ 8650.087540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8650.094862] alg: No test for adler32 (adler32-zlib) [ 8651.131995] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8651.297175] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8652.928813] Key type lgssc registered [ 8653.928214] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8662.999071] Lustre: DEBUG MARKER: Iteration 27 [ 8663.295623] LustreError: 364915:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8663.300340] LustreError: 364918:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8663.307238] LustreError: 364915:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8664.082224] Lustre: Mounted lustre-client [ 8665.157848] LustreError: 365026:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7060000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8665.181858] LustreError: 365026:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8665.209661] Lustre: Unmounted lustre-client [ 8667.952855] Key type lgssc unregistered [ 8668.205553] LNet: 365268:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8668.217523] LNetError: 365268:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8668.238444] LNet: Removed LNI 192.168.202.33@tcp [ 8668.829166] Key type .llcrypt unregistered [ 8668.830631] Key type ._llcrypt unregistered [ 8669.726734] Key type ._llcrypt registered [ 8669.728260] Key type .llcrypt registered [ 8670.185451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8670.197799] alg: No test for adler32 (adler32-zlib) [ 8671.244631] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8671.434897] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8673.122055] Key type lgssc registered [ 8674.133545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8684.893718] Lustre: DEBUG MARKER: Iteration 28 [ 8685.199941] LustreError: 366084:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8685.204874] LustreError: 366085:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8685.209616] LustreError: 366084:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8685.972558] Lustre: Mounted lustre-client [ 8687.196777] LustreError: 366194:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac52c7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8687.213981] LustreError: 366194:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8687.256293] Lustre: Unmounted lustre-client [ 8690.122971] Key type lgssc unregistered [ 8690.333141] LNet: 366436:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8690.337547] LNetError: 366436:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8690.357903] LNet: Removed LNI 192.168.202.33@tcp [ 8690.934438] Key type .llcrypt unregistered [ 8690.938705] Key type ._llcrypt unregistered [ 8691.631363] Key type ._llcrypt registered [ 8691.632927] Key type .llcrypt registered [ 8692.236850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8692.255964] alg: No test for adler32 (adler32-zlib) [ 8693.322907] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8693.579523] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8695.320216] Key type lgssc registered [ 8696.526464] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8706.109973] Lustre: DEBUG MARKER: Iteration 29 [ 8706.548908] LustreError: 367254:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8706.550162] LustreError: 367255:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8706.563564] LustreError: 367254:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 8707.307868] Lustre: Mounted lustre-client [ 8708.588829] LustreError: 367361:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8708.612434] LustreError: 367361:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8708.663868] Lustre: Unmounted lustre-client [ 8711.222643] Key type lgssc unregistered [ 8711.466064] LNet: 367606:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8711.475296] LNetError: 367606:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8711.497518] LNet: Removed LNI 192.168.202.33@tcp [ 8712.203189] Key type .llcrypt unregistered [ 8712.208212] Key type ._llcrypt unregistered [ 8712.875257] Key type ._llcrypt registered [ 8712.876791] Key type .llcrypt registered [ 8713.270936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8713.294316] alg: No test for adler32 (adler32-zlib) [ 8714.313512] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8714.520958] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8716.176217] Key type lgssc registered [ 8717.132322] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8726.964922] Lustre: DEBUG MARKER: Iteration 30 [ 8727.254180] LustreError: 368426:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8727.256125] LustreError: 368425:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8727.269260] LustreError: 368426:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8728.031420] Lustre: Mounted lustre-client [ 8728.032950] Lustre: Skipped 1 previous similar message [ 8729.136788] LustreError: 368529:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d19800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8729.158917] LustreError: 368529:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8729.183820] Lustre: Unmounted lustre-client [ 8731.687851] Key type lgssc unregistered [ 8731.966521] LNet: 368776:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8731.981278] LNetError: 368776:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8731.999058] LNet: Removed LNI 192.168.202.33@tcp [ 8732.744086] Key type .llcrypt unregistered [ 8732.745688] Key type ._llcrypt unregistered [ 8733.846219] Key type ._llcrypt registered [ 8733.864256] Key type .llcrypt registered [ 8734.448808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8734.502252] alg: No test for adler32 (adler32-zlib) [ 8735.674574] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8735.911822] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8737.752176] Key type lgssc registered [ 8739.119590] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8750.147871] Lustre: DEBUG MARKER: Iteration 31 [ 8750.487262] LustreError: 369596:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8750.489187] LustreError: 369591:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8750.505732] LustreError: 369596:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8751.223029] Lustre: Mounted lustre-client [ 8752.333814] LustreError: 369703:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad420d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8752.349366] LustreError: 369703:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 8752.386104] Lustre: Unmounted lustre-client [ 8754.384417] Key type lgssc unregistered [ 8754.613737] LNet: 369945:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8754.621737] LNetError: 369945:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8754.636900] LNet: Removed LNI 192.168.202.33@tcp [ 8755.199161] Key type .llcrypt unregistered [ 8755.207512] Key type ._llcrypt unregistered [ 8755.869260] Key type ._llcrypt registered [ 8755.870628] Key type .llcrypt registered [ 8756.070502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8756.086075] alg: No test for adler32 (adler32-zlib) [ 8757.059857] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8757.291406] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8758.988063] Key type lgssc registered [ 8759.914842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8767.751514] Lustre: DEBUG MARKER: Iteration 32 [ 8768.006781] LustreError: 370763:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8768.007080] LustreError: 370765:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8768.014186] LustreError: 370763:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8768.688579] Lustre: Mounted lustre-client [ 8768.692345] Lustre: Skipped 1 previous similar message [ 8769.693445] LustreError: 370873:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8769.699057] LustreError: 370873:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8769.704077] LustreError: 370872:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8769.738230] Lustre: Unmounted lustre-client [ 8771.949788] Key type lgssc unregistered [ 8772.207262] LNet: 371114:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8772.220920] LNetError: 371114:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8773.289958] LNet: Removed LNI 192.168.202.33@tcp [ 8773.807163] Key type .llcrypt unregistered [ 8773.811464] Key type ._llcrypt unregistered [ 8774.229802] Key type ._llcrypt registered [ 8774.241572] Key type .llcrypt registered [ 8774.607318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8774.619514] alg: No test for adler32 (adler32-zlib) [ 8775.677702] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8775.819437] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8777.480175] Key type lgssc registered [ 8778.412724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8787.094830] Lustre: DEBUG MARKER: Iteration 33 [ 8787.301642] LustreError: 371932:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8787.304580] LustreError: 371934:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8787.311691] LustreError: 371932:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8788.022720] Lustre: Mounted lustre-client [ 8789.305690] LustreError: 372041:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aedfc0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8789.326500] LustreError: 372042:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8789.331040] LustreError: 372041:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8789.371734] Lustre: Unmounted lustre-client [ 8791.767700] Key type lgssc unregistered [ 8791.977122] LNet: 372283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8791.991634] LNetError: 372283:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8792.010126] LNet: Removed LNI 192.168.202.33@tcp [ 8792.694291] Key type .llcrypt unregistered [ 8792.698938] Key type ._llcrypt unregistered [ 8793.364689] Key type ._llcrypt registered [ 8793.366290] Key type .llcrypt registered [ 8793.903671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8793.917135] alg: No test for adler32 (adler32-zlib) [ 8794.990183] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8795.169394] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8796.850427] Key type lgssc registered [ 8798.018719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8808.032739] Lustre: DEBUG MARKER: Iteration 34 [ 8808.268303] LustreError: 373099:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8808.271345] LustreError: 373107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8808.283806] LustreError: 373099:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8809.009572] Lustre: Mounted lustre-client [ 8810.177978] LustreError: 373210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac623d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8810.186730] LustreError: 373210:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8810.191829] LustreError: 373211:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8810.229321] Lustre: Unmounted lustre-client [ 8812.186514] Key type lgssc unregistered [ 8812.457976] LNet: 373452:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8812.464426] LNetError: 373452:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8812.483865] LNet: Removed LNI 192.168.202.33@tcp [ 8813.078186] Key type .llcrypt unregistered [ 8813.079583] Key type ._llcrypt unregistered [ 8813.522545] Key type ._llcrypt registered [ 8813.528042] Key type .llcrypt registered [ 8813.978204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8813.996532] alg: No test for adler32 (adler32-zlib) [ 8815.082619] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8815.245175] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8816.920226] Key type lgssc registered [ 8817.967164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8826.068920] Lustre: DEBUG MARKER: Iteration 35 [ 8826.289699] LustreError: 374271:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8826.292788] LustreError: 374272:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8826.302580] LustreError: 374271:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8827.024167] Lustre: Mounted lustre-client [ 8827.947754] LustreError: 374369:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7064000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8827.965154] LustreError: 374369:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8828.004969] Lustre: Unmounted lustre-client [ 8830.329598] Key type lgssc unregistered [ 8830.538964] LNet: 374616:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8830.550211] LNetError: 374616:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8830.577124] LNet: Removed LNI 192.168.202.33@tcp [ 8831.137762] Key type .llcrypt unregistered [ 8831.143282] Key type ._llcrypt unregistered [ 8831.593169] Key type ._llcrypt registered [ 8831.597160] Key type .llcrypt registered [ 8832.011246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8832.027073] alg: No test for adler32 (adler32-zlib) [ 8833.078164] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8833.342808] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8834.976526] Key type lgssc registered [ 8835.943981] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8843.976756] Lustre: DEBUG MARKER: Iteration 36 [ 8844.153789] LustreError: 375434:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8844.154108] LustreError: 375435:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8844.160553] LustreError: 375434:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8844.794050] Lustre: Mounted lustre-client [ 8845.620771] LustreError: 375537:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad71f6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8845.644627] LustreError: 375537:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8845.675391] Lustre: Unmounted lustre-client [ 8847.417989] Key type lgssc unregistered [ 8847.624064] LNet: 375784:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8847.638300] LNetError: 375784:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8848.679388] LNet: Removed LNI 192.168.202.33@tcp [ 8849.175145] Key type .llcrypt unregistered [ 8849.176381] Key type ._llcrypt unregistered [ 8849.861127] Key type ._llcrypt registered [ 8849.864095] Key type .llcrypt registered [ 8849.975067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8849.984254] alg: No test for adler32 (adler32-zlib) [ 8850.995205] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8851.164763] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8852.816205] Key type lgssc registered [ 8853.771388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8862.596179] Lustre: DEBUG MARKER: Iteration 37 [ 8862.923575] LustreError: 376604:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8862.924691] LustreError: 376605:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8862.932014] LustreError: 376604:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8863.611967] Lustre: Mounted lustre-client [ 8863.621635] Lustre: Skipped 1 previous similar message [ 8864.603939] LustreError: 376707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af30a8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8864.626126] LustreError: 376707:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8864.655151] Lustre: Unmounted lustre-client [ 8866.940517] Key type lgssc unregistered [ 8867.189128] LNet: 376956:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8867.199816] LNetError: 376956:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8867.211318] LNet: Removed LNI 192.168.202.33@tcp [ 8867.710281] Key type .llcrypt unregistered [ 8867.713449] Key type ._llcrypt unregistered [ 8868.180306] Key type ._llcrypt registered [ 8868.183932] Key type .llcrypt registered [ 8868.520352] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8868.540270] alg: No test for adler32 (adler32-zlib) [ 8869.556037] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8869.696111] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8871.320369] Key type lgssc registered [ 8872.312180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8881.056386] Lustre: DEBUG MARKER: Iteration 38 [ 8881.365521] LustreError: 377772:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8881.367469] LustreError: 377779:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8881.383344] LustreError: 377772:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 8882.158242] Lustre: Mounted lustre-client [ 8883.338319] LustreError: 377876:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad71f7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8883.363280] LustreError: 377876:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8883.407510] Lustre: Unmounted lustre-client [ 8886.572506] Key type lgssc unregistered [ 8886.970313] LNet: 378124:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8886.981666] LNetError: 378124:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8888.044857] LNet: Removed LNI 192.168.202.33@tcp [ 8889.172151] Key type .llcrypt unregistered [ 8889.176585] Key type ._llcrypt unregistered [ 8890.967575] Key type ._llcrypt registered [ 8890.997123] Key type .llcrypt registered [ 8891.922815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8891.954332] alg: No test for adler32 (adler32-zlib) [ 8893.233961] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8893.708738] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8895.432587] Key type lgssc registered [ 8896.794840] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8907.722436] Lustre: DEBUG MARKER: Iteration 39 [ 8908.175320] LustreError: 378943:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8908.178427] LustreError: 378944:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8908.194332] LustreError: 378943:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8908.986583] Lustre: Mounted lustre-client [ 8910.191015] LustreError: 379047:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8910.230596] LustreError: 379047:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8910.275131] Lustre: Unmounted lustre-client [ 8913.133360] Key type lgssc unregistered [ 8913.494748] LNet: 379292:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8913.501607] LNetError: 379292:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8913.521907] LNet: Removed LNI 192.168.202.33@tcp [ 8914.418243] Key type .llcrypt unregistered [ 8914.421696] Key type ._llcrypt unregistered [ 8915.359691] Key type ._llcrypt registered [ 8915.362161] Key type .llcrypt registered [ 8915.779787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8915.791659] alg: No test for adler32 (adler32-zlib) [ 8916.952577] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8917.333466] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8919.048828] Key type lgssc registered [ 8920.396313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8931.042962] Lustre: DEBUG MARKER: Iteration 40 [ 8931.337265] LustreError: 380107:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8931.341648] LustreError: 380113:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8931.358202] LustreError: 380107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 8932.091821] Lustre: Mounted lustre-client [ 8933.153456] LustreError: 380219:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af30af800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8933.164930] LustreError: 380219:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8933.187557] Lustre: Unmounted lustre-client [ 8933.193617] Lustre: Skipped 1 previous similar message [ 8935.228269] Key type lgssc unregistered [ 8935.396738] LNet: 380461:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8935.401683] LNetError: 380461:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8935.414540] LNet: Removed LNI 192.168.202.33@tcp [ 8935.947154] Key type .llcrypt unregistered [ 8935.949268] Key type ._llcrypt unregistered [ 8936.570030] Key type ._llcrypt registered [ 8936.572265] Key type .llcrypt registered [ 8937.104780] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8937.113481] alg: No test for adler32 (adler32-zlib) [ 8938.179665] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8938.490441] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8940.240288] Key type lgssc registered [ 8941.413335] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8950.980247] Lustre: DEBUG MARKER: Iteration 41 [ 8951.301184] LustreError: 381280:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8951.302343] LustreError: 381281:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8951.329552] LustreError: 381280:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8952.110889] Lustre: Mounted lustre-client [ 8953.316840] LustreError: 381383:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aedfc1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8953.343222] LustreError: 381383:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8953.393125] Lustre: Unmounted lustre-client [ 8955.587051] Key type lgssc unregistered [ 8955.748383] LNet: 381631:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8955.752483] LNetError: 381631:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8955.760156] LNet: Removed LNI 192.168.202.33@tcp [ 8956.206436] Key type .llcrypt unregistered [ 8956.207983] Key type ._llcrypt unregistered [ 8956.763316] Key type ._llcrypt registered [ 8956.764690] Key type .llcrypt registered [ 8956.978230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8956.990728] alg: No test for adler32 (adler32-zlib) [ 8957.934702] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8958.087645] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8959.704692] Key type lgssc registered [ 8960.711480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8968.753757] Lustre: DEBUG MARKER: Iteration 42 [ 8969.175935] LustreError: 382449:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8969.176286] LustreError: 382451:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8969.189178] LustreError: 382449:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [ 8969.898811] Lustre: Mounted lustre-client [ 8971.203239] LustreError: 382556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3b56000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8971.217403] LustreError: 382556:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8971.245810] Lustre: Unmounted lustre-client [ 8973.251967] Key type lgssc unregistered [ 8973.419933] LNet: 382799:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8973.424374] LNetError: 382799:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8973.440446] LNet: Removed LNI 192.168.202.33@tcp [ 8973.980169] Key type .llcrypt unregistered [ 8973.981563] Key type ._llcrypt unregistered [ 8974.542879] Key type ._llcrypt registered [ 8974.546331] Key type .llcrypt registered [ 8974.893319] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8974.908211] alg: No test for adler32 (adler32-zlib) [ 8975.875142] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8976.017805] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8977.648178] Key type lgssc registered [ 8978.542162] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8986.839284] Lustre: DEBUG MARKER: Iteration 43 [ 8987.104452] LustreError: 383618:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8987.105860] LustreError: 383619:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8987.116542] LustreError: 383618:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 8987.806520] Lustre: Mounted lustre-client [ 8988.848634] LustreError: 383727:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ad0cff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8988.863641] LustreError: 383727:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8988.891938] Lustre: Unmounted lustre-client [ 8990.623827] Key type lgssc unregistered [ 8990.812780] LNet: 383968:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8990.819123] LNetError: 383968:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8990.829657] LNet: Removed LNI 192.168.202.33@tcp [ 8991.242053] Key type .llcrypt unregistered [ 8991.243785] Key type ._llcrypt unregistered [ 8991.735802] Key type ._llcrypt registered [ 8991.738506] Key type .llcrypt registered [ 8992.011192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8992.019719] alg: No test for adler32 (adler32-zlib) [ 8993.090702] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 8993.272189] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 8994.928731] Key type lgssc registered [ 8995.964498] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9003.690337] Lustre: DEBUG MARKER: Iteration 44 [ 9003.880181] LustreError: 384787:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9003.880215] LustreError: 384788:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9003.888270] LustreError: 384787:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9004.523823] Lustre: Mounted lustre-client [ 9005.297340] LustreError: 384896:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac6ef6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9005.313107] LustreError: 384896:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9005.334078] Lustre: Unmounted lustre-client [ 9006.918956] Key type lgssc unregistered [ 9007.153283] LNet: 385138:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9007.163093] LNetError: 385138:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9007.176583] LNet: Removed LNI 192.168.202.33@tcp [ 9007.544163] Key type .llcrypt unregistered [ 9007.546183] Key type ._llcrypt unregistered [ 9007.967644] Key type ._llcrypt registered [ 9007.969274] Key type .llcrypt registered [ 9008.283487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9008.304418] alg: No test for adler32 (adler32-zlib) [ 9009.317301] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9009.482201] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9011.144549] Key type lgssc registered [ 9011.917325] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9019.379946] Lustre: DEBUG MARKER: Iteration 45 [ 9019.746349] LustreError: 385956:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9019.750500] LustreError: 385961:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9019.757158] LustreError: 385956:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9020.453477] Lustre: Mounted lustre-client [ 9021.458258] LustreError: 386062:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3ba0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9021.471088] LustreError: 386062:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9021.510114] Lustre: Unmounted lustre-client [ 9023.576945] Key type lgssc unregistered [ 9023.759679] LNet: 386304:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9023.762791] LNetError: 386304:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9023.772581] LNet: Removed LNI 192.168.202.33@tcp [ 9024.193163] Key type .llcrypt unregistered [ 9024.196106] Key type ._llcrypt unregistered [ 9024.686310] Key type ._llcrypt registered [ 9024.708839] Key type .llcrypt registered [ 9025.068452] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9025.082159] alg: No test for adler32 (adler32-zlib) [ 9026.125024] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9026.295212] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9027.912641] Key type lgssc registered [ 9028.897719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9039.096669] Lustre: DEBUG MARKER: Iteration 46 [ 9039.586407] LustreError: 387122:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9039.587489] LustreError: 387123:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9039.609286] LustreError: 387122:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [ 9040.414954] Lustre: Mounted lustre-client [ 9042.133932] LustreError: 387232:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5aedfc2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9042.144505] LustreError: 387232:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9042.165590] LustreError: 387233:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9042.177914] LustreError: 387233:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9042.230113] Lustre: Unmounted lustre-client [ 9045.213248] Key type lgssc unregistered [ 9045.625964] LNet: 387473:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9045.637713] LNetError: 387473:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9045.657456] LNet: Removed LNI 192.168.202.33@tcp [ 9046.551495] Key type .llcrypt unregistered [ 9046.553234] Key type ._llcrypt unregistered [ 9047.402332] Key type ._llcrypt registered [ 9047.415578] Key type .llcrypt registered [ 9048.116862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9048.136513] alg: No test for adler32 (adler32-zlib) [ 9049.213941] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9049.418706] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9051.064188] Key type lgssc registered [ 9052.603552] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9062.204430] Lustre: DEBUG MARKER: Iteration 47 [ 9062.452404] LustreError: 388292:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9062.455453] LustreError: 388293:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9062.462469] LustreError: 388292:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9063.141893] Lustre: Mounted lustre-client [ 9064.260671] LustreError: 388396:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5afe5e8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9064.292194] LustreError: 388396:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9064.324724] Lustre: Unmounted lustre-client [ 9066.237902] Key type lgssc unregistered [ 9066.440245] LNet: 388645:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9066.443090] LNetError: 388645:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9066.468279] LNet: Removed LNI 192.168.202.33@tcp [ 9066.916263] Key type .llcrypt unregistered [ 9066.918332] Key type ._llcrypt unregistered [ 9067.472600] Key type ._llcrypt registered [ 9067.474141] Key type .llcrypt registered [ 9067.735059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9067.745203] alg: No test for adler32 (adler32-zlib) [ 9068.796564] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9068.996872] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9070.648148] Key type lgssc registered [ 9071.436402] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9079.465458] Lustre: DEBUG MARKER: Iteration 48 [ 9079.720635] LustreError: 389461:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9079.721283] LustreError: 389462:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9079.729531] LustreError: 389461:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9081.472954] Lustre: Mounted lustre-client [ 9082.621168] LustreError: 389569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9082.640743] LustreError: 389569:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9082.682194] Lustre: Unmounted lustre-client [ 9085.033213] Key type lgssc unregistered [ 9085.289239] LNet: 389811:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9085.294706] LNetError: 389811:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9085.305283] LNet: Removed LNI 192.168.202.33@tcp [ 9085.939821] Key type .llcrypt unregistered [ 9085.946518] Key type ._llcrypt unregistered [ 9086.637343] Key type ._llcrypt registered [ 9086.640082] Key type .llcrypt registered [ 9087.115876] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9087.132271] alg: No test for adler32 (adler32-zlib) [ 9088.164804] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9088.340230] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9090.008950] Key type lgssc registered [ 9091.077723] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9101.723793] Lustre: DEBUG MARKER: Iteration 49 [ 9102.076777] LustreError: 390638:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9102.076942] LustreError: 390632:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9102.087899] LustreError: 390638:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9102.804888] Lustre: Mounted lustre-client [ 9104.010147] LustreError: 390735:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3b50000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9104.029114] LustreError: 390735:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9104.056245] Lustre: Unmounted lustre-client [ 9106.459930] Key type lgssc unregistered [ 9106.698871] LNet: 390983:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9106.706413] LNetError: 390983:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9106.733720] LNet: Removed LNI 192.168.202.33@tcp [ 9107.282285] Key type .llcrypt unregistered [ 9107.285802] Key type ._llcrypt unregistered [ 9107.987578] Key type ._llcrypt registered [ 9107.989101] Key type .llcrypt registered [ 9108.307527] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9108.318172] alg: No test for adler32 (adler32-zlib) [ 9109.383500] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9109.616322] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9111.280181] Key type lgssc registered [ 9112.544884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9124.157878] Lustre: DEBUG MARKER: Iteration 50 [ 9124.743321] LustreError: 391803:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9124.746211] LustreError: 391804:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9124.771076] LustreError: 391803:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4977 [ 9125.641263] Lustre: Mounted lustre-client [ 9127.122630] LustreError: 391905:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac3b55000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9127.160460] LustreError: 391905:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9127.307815] Lustre: Unmounted lustre-client [ 9130.647426] Key type lgssc unregistered [ 9130.965384] LNet: 392154:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9130.979920] LNetError: 392154:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9131.008859] LNet: Removed LNI 192.168.202.33@tcp [ 9131.820156] Key type .llcrypt unregistered [ 9131.827023] Key type ._llcrypt unregistered [ 9132.400724] Key type ._llcrypt registered [ 9132.402304] Key type .llcrypt registered [ 9133.159348] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9133.234428] alg: No test for adler32 (adler32-zlib) [ 9134.362182] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 9134.727356] LNet: Added LNI 192.168.202.33@tcp [8/256/0/180] [ 9136.488136] Key type lgssc registered [ 9138.192273] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9150.158974] Lustre: Mounted lustre-client [ 9156.850563] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 06:45:36 (1761216336) [ 9173.472264] Lustre: 393487:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216338/real 1761216338] req@ffff9d5af61bf800 x1846769158727424/t0(0) o36->lustre-MDT0000-mdc-ffff9d5af2e60000@192.168.202.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1761216354 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9173.498204] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9173.540578] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9189.861426] Lustre: 393487:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216354/real 1761216354] req@ffff9d5af61bf800 x1846769158727424/t0(0) o36->lustre-MDT0000-mdc-ffff9d5af2e60000@192.168.202.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1761216370 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9189.923363] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9189.951784] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9205.216183] Lustre: 393487:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216370/real 1761216370] req@ffff9d5af61bf800 x1846769158727424/t0(0) o36->lustre-MDT0000-mdc-ffff9d5af2e60000@192.168.202.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1761216386 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9205.262754] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9205.300675] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9221.600310] Lustre: 393487:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216386/real 1761216386] req@ffff9d5af61bf800 x1846769158727424/t0(0) o36->lustre-MDT0000-mdc-ffff9d5af2e60000@192.168.202.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1761216402 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9221.620112] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9221.648253] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9237.984161] Lustre: 393487:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216402/real 1761216402] req@ffff9d5af61bf800 x1846769158727424/t0(0) o36->lustre-MDT0000-mdc-ffff9d5af2e60000@192.168.202.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1761216418 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9238.032704] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection to lustre-MDT0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9238.090032] Lustre: lustre-MDT0000-mdc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9239.821468] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 06:46:59 (1761216419) [ 9241.333476] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 9242.642358] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 06:47:02 (1761216422) [ 9244.076597] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 9245.457130] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 06:47:05 (1761216425) [ 9251.037973] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 06:47:10 (1761216430) [ 9252.371234] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 9253.808318] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 06:47:13 (1761216433) [ 9255.122220] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 9256.602374] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 06:47:16 (1761216436) [ 9257.765430] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 9259.681950] Lustre: DEBUG MARKER: == sanityn test 117a: TCU: Init and enable Trash Can on MDTs ========================================================== 06:47:18 (1761216438) [ 9267.372944] Lustre: DEBUG MARKER: == sanityn test 117b: Move regular file and empty dir into trash can dir ========================================================== 06:47:27 (1761216447) [ 9268.623887] Lustre: DEBUG MARKER: SKIP: sanityn test_117b ldiskfs only test [ 9270.181681] Lustre: DEBUG MARKER: == sanityn test 117c: Move deleted tree with multiple levels into trash ========================================================== 06:47:29 (1761216449) [ 9271.424392] Lustre: DEBUG MARKER: SKIP: sanityn test_117c ldiskfs only test [ 9272.894529] Lustre: DEBUG MARKER: == sanityn test 117d: Per-User Trash can Type testing ==== 06:47:32 (1761216452) [ 9274.342392] Lustre: DEBUG MARKER: SKIP: sanityn test_117d ldiskfs only test [ 9275.809427] Lustre: DEBUG MARKER: == sanityn test 117e: Undeleted dir in trash should keep its original xattrs ========================================================== 06:47:35 (1761216455) [ 9277.007239] Lustre: DEBUG MARKER: SKIP: sanityn test_117e ldiskfs only test [ 9278.616969] Lustre: DEBUG MARKER: == sanityn test 117f: Uncache the dentry under the trash dir ========================================================== 06:47:38 (1761216458) [ 9279.830777] Lustre: DEBUG MARKER: SKIP: sanityn test_117f ldiskfs only test [ 9281.821774] Lustre: DEBUG MARKER: == sanityn test 117g: Access .Trash for a non-striped directory ========================================================== 06:47:41 (1761216461) [ 9283.162578] Lustre: DEBUG MARKER: SKIP: sanityn test_117g ldiskfs only test [ 9284.783776] Lustre: DEBUG MARKER: == sanityn test 117h: Permission testing of various operations for files in Trash Can ========================================================== 06:47:44 (1761216464) [ 9289.374187] LustreError: 398735:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9d5af2e60000: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [ 9290.079944] LustreError: 398759:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9d5af2e60000: inode [0x200000403:0x8:0x0] mdc close failed: rc = -9 [ 9290.084991] LustreError: 398759:0:(file.c:249:ll_close_inode_openhandle()) Skipped 2 previous similar messages [ 9290.234697] LustreError: 398769:0:(llite_lib.c:2047:ll_md_setattr()) md_setattr fails: rc = -9 [ 9297.407957] LustreError: 399336:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9d5af2e60000: inode [0x200000403:0x10:0x0] mdc close failed: rc = -9 [ 9297.414357] LustreError: 399336:0:(file.c:249:ll_close_inode_openhandle()) Skipped 6 previous similar messages [ 9298.985763] Lustre: DEBUG MARKER: == sanityn test 117i: Forbid to create a file with name .Trash ========================================================== 06:47:58 (1761216478) [ 9304.951028] Lustre: DEBUG MARKER: == sanityn test 117j: Move back file from Trash Can ====== 06:48:04 (1761216484) [ 9306.164107] Lustre: DEBUG MARKER: SKIP: sanityn test_117j ldiskfs only test [ 9308.023622] Lustre: DEBUG MARKER: == sanityn test 117k: Unrm file from Trash Can =========== 06:48:07 (1761216487) [ 9309.579277] Lustre: DEBUG MARKER: SKIP: sanityn test_117k ldiskfs only test [ 9311.273287] Lustre: DEBUG MARKER: == sanityn test 117l: Clean up files in Trash Can via command 'lfs trash clean' ========================================================== 06:48:10 (1761216490) [ 9312.746359] Lustre: DEBUG MARKER: SKIP: sanityn test_117l ldiskfs only test [ 9314.487450] Lustre: DEBUG MARKER: == sanityn test 117m: Empty Trash Can via the command 'lfs trash clean' ========================================================== 06:48:14 (1761216494) [ 9316.006036] Lustre: DEBUG MARKER: SKIP: sanityn test_117m ldiskfs only test [ 9317.619480] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 06:48:17 (1761216497) [ 9337.312164] Lustre: 392344:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761216502/real 1761216502] req@ffff9d5af61bc000 x1846769158812032/t0(0) o4->lustre-OST0000-osc-ffff9d5af2e60000@192.168.202.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1761216518 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9337.312198] Lustre: lustre-OST0000-osc-ffff9d5af2e60000: Connection to lustre-OST0000 (at 192.168.202.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9337.372546] Lustre: 392344:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 9337.406771] Lustre: lustre-OST0000-osc-ffff9d5af2e60000: Connection restored to 192.168.202.133@tcp (at 192.168.202.133@tcp) [ 9431.480075] Lustre: DEBUG MARKER: oleg233-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d5ac7d18800.ost_server_uuid 50 [ 9432.915693] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d5ac7d18800.ost_server_uuid in IDLE state after 0 sec [ 9434.550815] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9435.985787] Lustre: DEBUG MARKER: == sanityn test complete, duration 9202 sec ============== 06:50:15 (1761216615) [ 9437.518254] Lustre: DEBUG MARKER: === sanityn: start cleanup 06:50:17 (1761216617) === [ 9656.286336] LustreError: 402705:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5ac7d18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9656.304513] LustreError: 402705:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9656.333969] Lustre: Unmounted lustre-client [ 9660.210524] Lustre: DEBUG MARKER: === sanityn: finish cleanup 06:53:59 (1761216839) === [ 9661.298521] LustreError: 403007:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d5af2e60000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9661.310261] LustreError: 403007:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9661.320639] LustreError: 403007:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9661.322994] LustreError: 403007:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 9661.360494] Lustre: Unmounted lustre-client [ 9684.059924] Key type lgssc unregistered [ 9684.305942] LNet: 403489:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9684.318043] LNetError: 403489:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9685.354343] LNet: Removed LNI 192.168.202.33@tcp [ 9686.055729] Key type .llcrypt unregistered [ 9686.060658] Key type ._llcrypt unregistered