[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 436381454 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.996 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003335] x2apic enabled [ 0.004007] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008331] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 0.009025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399996) [ 0.010011] pid_max: default: 32768 minimum: 301 [ 0.011106] LSM: Security Framework initializing [ 0.012033] Yama: becoming mindful. [ 0.013020] SELinux: Initializing. [ 0.013733] *** VALIDATE selinux *** [ 0.021644] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026454] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027151] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028000] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029076] *** VALIDATE tmpfs *** [ 0.030334] *** VALIDATE proc *** [ 0.031200] *** VALIDATE cgroup *** [ 0.032005] *** VALIDATE cgroup2 *** [ 0.033210] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034148] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036023] Spectre V2 : User space: Vulnerable [ 0.037004] Speculative Store Bypass: Vulnerable [ 0.040258] debug: unmapping init [mem 0xffffffffb0059000-0xffffffffb0060fff] [ 0.043000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043545] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044014] ... version: 2 [ 0.045009] ... bit width: 48 [ 0.046008] ... generic registers: 4 [ 0.047012] ... value mask: 0000ffffffffffff [ 0.048012] ... max period: 00007fffffffffff [ 0.049009] ... fixed-purpose events: 3 [ 0.050008] ... event mask: 000000070000000f [ 0.051314] rcu: Hierarchical SRCU implementation. [ 0.053374] smp: Bringing up secondary CPUs ... [ 0.054494] x86: Booting SMP configuration: [ 0.055017] .... node #0, CPUs: #1 #2 #3 [ 0.064089] smp: Brought up 1 node, 4 CPUs [ 0.066023] smpboot: Max logical packages: 1 [ 0.067013] smpboot: Total of 4 processors activated (19199.96 BogoMIPS) [ 0.138655] node 0 deferred pages initialised in 68ms [ 0.147030] devtmpfs: initialized [ 0.149529] x86/mm: Memory block size: 128MB [ 0.159265] gcov: version magic: 0x41383552 [ 0.163021] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.164065] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.165218] pinctrl core: initialized pinctrl subsystem [ 0.166191] [ 0.166739] ************************************************************* [ 0.167010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.168010] ** ** [ 0.169014] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.170011] ** ** [ 0.171011] ** This means that this kernel is built to expose internal ** [ 0.172011] ** IOMMU data structures, which may compromise security on ** [ 0.173010] ** your system. ** [ 0.174010] ** ** [ 0.175010] ** If you see this message and you are not debugging the ** [ 0.176009] ** kernel, report this immediately to your vendor! ** [ 0.177009] ** ** [ 0.178011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.179008] ************************************************************* [ 0.180707] NET: Registered protocol family 16 [ 0.181399] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.182064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.183085] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.188146] cpuidle: using governor menu [ 0.204011] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.213543] PCI: Using configuration type 1 for base access [ 0.218135] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.234234] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.235027] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.236199] cryptd: max_cpu_qlen set to 1000 [ 0.238355] ACPI: Added _OSI(Module Device) [ 0.240015] ACPI: Added _OSI(Processor Device) [ 0.241009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.242012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.248745] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.254711] ACPI: Interpreter enabled [ 0.257072] ACPI: PM: (supports S0 S3 S4 S5) [ 0.258010] ACPI: Using IOAPIC for interrupt routing [ 0.260154] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.263466] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.274394] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.276028] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.278013] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.282070] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.287725] acpiphp: Slot [2] registered [ 0.289102] acpiphp: Slot [5] registered [ 0.290109] acpiphp: Slot [6] registered [ 0.292082] acpiphp: Slot [3] registered [ 0.293080] acpiphp: Slot [4] registered [ 0.295068] acpiphp: Slot [7] registered [ 0.296068] acpiphp: Slot [8] registered [ 0.297093] acpiphp: Slot [9] registered [ 0.299088] acpiphp: Slot [10] registered [ 0.300084] acpiphp: Slot [11] registered [ 0.301065] acpiphp: Slot [12] registered [ 0.303064] acpiphp: Slot [13] registered [ 0.306118] acpiphp: Slot [14] registered [ 0.308116] acpiphp: Slot [15] registered [ 0.310101] acpiphp: Slot [16] registered [ 0.312094] acpiphp: Slot [17] registered [ 0.313084] acpiphp: Slot [18] registered [ 0.314069] acpiphp: Slot [19] registered [ 0.316068] acpiphp: Slot [20] registered [ 0.317062] acpiphp: Slot [21] registered [ 0.318367] acpiphp: Slot [22] registered [ 0.320099] acpiphp: Slot [23] registered [ 0.324131] acpiphp: Slot [24] registered [ 0.326116] acpiphp: Slot [25] registered [ 0.328090] acpiphp: Slot [26] registered [ 0.329085] acpiphp: Slot [27] registered [ 0.331101] acpiphp: Slot [28] registered [ 0.333218] acpiphp: Slot [29] registered [ 0.335121] acpiphp: Slot [30] registered [ 0.337088] acpiphp: Slot [31] registered [ 0.339077] PCI host bridge to bus 0000:00 [ 0.340034] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.343026] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.345016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.347019] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.349016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.352032] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.353201] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.357730] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.363169] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.373488] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.379383] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.381011] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.383017] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.384016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.388323] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.394751] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.398038] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.401946] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.405015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.413826] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.420012] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.424839] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.429021] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.441016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.455013] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.465957] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.479020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.486051] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.500014] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.513727] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.515364] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.518347] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.520297] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.521246] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.526239] iommu: Default domain type: Passthrough [ 0.528402] SCSI subsystem initialized [ 0.530122] ACPI: bus type USB registered [ 0.532093] usbcore: registered new interface driver usbfs [ 0.534056] usbcore: registered new interface driver hub [ 0.539106] usbcore: registered new device driver usb [ 0.541162] pps_core: LinuxPPS API ver. 1 registered [ 0.544011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.547047] PTP clock support registered [ 0.549132] EDAC MC: Ver: 3.0.0 [ 0.550321] PCI: Using ACPI for IRQ routing [ 0.552810] NetLabel: Initializing [ 0.554012] NetLabel: domain hash size = 128 [ 0.557011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.559080] NetLabel: unlabeled traffic allowed by default [ 0.562154] vgaarb: loaded [ 0.563268] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.566014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.571370] clocksource: Switched to clocksource kvm-clock [ 0.687656] VFS: Disk quotas dquot_6.6.0 [ 0.692331] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.695248] *** VALIDATE ramfs *** [ 0.696692] *** VALIDATE hugetlbfs *** [ 0.698482] pnp: PnP ACPI init [ 0.701506] pnp: PnP ACPI: found 6 devices [ 0.717966] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.722325] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.725064] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.728247] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.731114] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.734702] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.738217] NET: Registered protocol family 2 [ 0.741166] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.747243] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.752594] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.760542] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.764625] TCP: Hash tables configured (established 65536 bind 65536) [ 0.767751] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.771864] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.775968] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.780379] NET: Registered protocol family 1 [ 0.783336] RPC: Registered named UNIX socket transport module. [ 0.785497] RPC: Registered udp transport module. [ 0.787171] RPC: Registered tcp transport module. [ 0.788970] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.791449] NET: Registered protocol family 44 [ 0.793584] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.795490] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.797263] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.798886] PCI: CLS 0 bytes, default 64 [ 0.800325] Unpacking initramfs... [ 2.340541] debug: unmapping init [mem 0xffff9c61fcc64000-0xffff9c61fffcffff] [ 2.344828] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.346742] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.349220] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229833f6470, max_idle_ns: 440795327230 ns [ 2.925036] Initialise system trusted keyrings [ 2.926573] Key type blacklist registered [ 2.928537] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.938531] zbud: loaded [ 2.941600] *** VALIDATE nfs *** [ 2.943016] *** VALIDATE nfs4 *** [ 2.944870] pstore: using deflate compression [ 2.948936] Platform Keyring initialized [ 3.047881] NET: Registered protocol family 38 [ 3.049773] Key type asymmetric registered [ 3.051200] Asymmetric key parser 'x509' registered [ 3.053722] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.057533] io scheduler mq-deadline registered [ 3.059343] io scheduler kyber registered [ 3.061245] io scheduler bfq registered [ 3.063407] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.067214] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.069954] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.076364] ACPI: Power Button [PWRF] [ 3.082567] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.089589] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.099633] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.128836] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.158682] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.163584] Non-volatile memory driver v1.3 [ 3.165401] Linux agpgart interface v0.103 [ 3.201647] virtio_blk virtio1: [vda] 134096 512-byte logical blocks (68.7 MB/65.5 MiB) [ 3.207153] vda: detected capacity change from 0 to 68657152 [ 3.231716] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.235653] vdb: detected capacity change from 0 to 1073741824 [ 3.248728] libphy: Fixed MDIO Bus: probed [ 3.266697] usbcore: registered new interface driver usbserial_generic [ 3.270537] usbserial: USB Serial support registered for generic [ 3.274410] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.283371] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.286418] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.290262] mousedev: PS/2 mouse device common for all mice [ 3.294549] rtc_cmos 00:05: RTC can wake from S4 [ 3.298399] rtc_cmos 00:05: registered as rtc0 [ 3.299577] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.301551] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.310833] intel_pstate: CPU model not supported [ 3.313200] hid: raw HID events driver (C) Jiri Kosina [ 3.315404] usbcore: registered new interface driver usbhid [ 3.318191] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.318819] usbhid: USB HID core driver [ 3.323198] drop_monitor: Initializing network drop monitor service [ 3.326536] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.327456] Initializing XFRM netlink socket [ 3.332584] NET: Registered protocol family 10 [ 3.335123] Segment Routing with IPv6 [ 3.336254] NET: Registered protocol family 17 [ 3.337567] mpls_gso: MPLS GSO support [ 3.340707] RAS: Correctable Errors collector initialized. [ 3.342225] AVX version of gcm_enc/dec engaged. [ 3.343243] AES CTR mode by8 optimization enabled [ 3.418286] sched_clock: Marking stable (3418220371, 0)->(4299586865, -881366494) [ 3.421321] registered taskstats version 1 [ 3.423453] Loading compiled-in X.509 certificates [ 3.425677] zswap: loaded using pool lzo/zbud [ 3.447323] Key type big_key registered [ 3.458826] Key type encrypted registered [ 3.460766] ima: No TPM chip found, activating TPM-bypass! [ 3.463061] ima: Allocated hash algorithm: sha1 [ 3.465166] ima: No architecture policies found [ 3.466842] evm: Initialising EVM extended attributes: [ 3.469195] evm: security.selinux [ 3.470416] evm: security.ima [ 3.471631] evm: security.capability [ 3.473099] evm: HMAC attrs: 0x1 [ 3.475638] rtc_cmos 00:05: setting system clock to 2026-01-16 06:55:29 UTC (1768546529) [ 3.480826] debug: unmapping init [mem 0xffffffffb1003000-0xffffffffb11fffff] [ 3.483345] debug: unmapping init [mem 0xffffffffafd82000-0xffffffffb0058fff] [ 3.493067] Write protecting the kernel read-only data: 28672k [ 3.496722] debug: unmapping init [mem 0xffffffffae403000-0xffffffffae5fffff] [ 3.498690] debug: unmapping init [mem 0xffffffffaed14000-0xffffffffaedfffff] [ 3.532248] 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.542832] systemd[1]: Detected virtualization kvm. [ 3.545579] systemd[1]: Detected architecture x86-64. [ 3.549811] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.579233] systemd[1]: No hostname configured. [ 3.580877] systemd[1]: Set hostname to . [ 3.582830] random: systemd: uninitialized urandom read (16 bytes read) [ 3.585297] systemd[1]: Initializing machine ID from random generator. [ 3.695930] random: ln: uninitialized urandom read (6 bytes read) [ 3.785894] random: systemd: uninitialized urandom read (16 bytes read) [ 3.789263] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.797401] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.801496] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.677896] device-mapper: uevent: version 1.0.3 [ 4.680391] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.625112] virtio_net virtio0 ens2: renamed from eth0 [ 5.778310] scsi host0: ata_piix [ 6.172524] scsi host1: ata_piix [ 6.175038] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 6.179676] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 11.110696] random: crng init done [ 11.112052] random: 7 urandom warning(s) missed due to ratelimiting [ 11.419590] dracut-initqueue[581]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 12.982126] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.560429] printk: systemd: 25 output lines suppressed due to ratelimiting [ 14.919912] SELinux: Disabled at runtime. [ 14.994590] 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) [ 15.010136] systemd[1]: Detected virtualization kvm. [ 15.012421] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.868988] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.873117] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.878458] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.884172] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.904739] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.914566] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.929613] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Listening on udev Control Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. Mounting Kernel Debug File System... Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd File Systems. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Stopped target Initrd Root File System. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ 16.344729] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ 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. [ 16.885127] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 17.853662] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 17.859045] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.635086] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 18.678317] EDAC sbridge: Ver: 1.1.2 [ 21.316075] Key type dns_resolver registered [ 21.651596] NFS: Registering the id_resolver key type [ 21.652826] Key type id_resolver registered [ 21.653809] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. Starting Login Service... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Hostname Service... Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Login Service. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg133-client login: [ 56.225859] libcfs: loading out-of-tree module taints kernel. [ 56.254987] Key type ._llcrypt registered [ 56.256694] Key type .llcrypt registered [ 56.724610] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 56.734259] alg: No test for adler32 (adler32-zlib) [ 57.999294] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [ 58.652672] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [ 60.367214] Key type lgssc registered [ 61.653627] Lustre: Echo OBD driver; http://www.lustre.org/ [ 151.992754] Lustre: Mounted lustre-client [ 155.104041] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 169.395321] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing check_logdir /tmp/testlogs/ [ 172.998830] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing yml_node [ 175.605873] Lustre: DEBUG MARKER: Client: 2.17.50.30 [ 177.268665] Lustre: DEBUG MARKER: MDS: 2.17.50.30 [ 177.631279] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 24s idle [ 178.791954] Lustre: DEBUG MARKER: OSS: 2.17.50.30 [ 179.997957] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Jan 16 01:58:24 EST 2026 [ 191.899487] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 193.235862] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 194.086927] Lustre: DEBUG MARKER: === sanityn: start setup 01:58:39 (1768546719) === [ 194.801904] Lustre: Mounted lustre-client [ 197.633253] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing check_config_client /mnt/lustre [ 212.479402] hrtimer: interrupt took 132393843 ns [ 218.440298] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 229.889737] Lustre: DEBUG MARKER: === sanityn: finish setup 01:59:14 (1768546754) === [ 231.375482] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 01:59:16 (1768546756) [ 236.204057] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 01:59:21 (1768546761) [ 240.122457] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 01:59:25 (1768546765) [ 243.937804] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 01:59:29 (1768546769) [ 247.876435] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 01:59:33 (1768546773) [ 251.543465] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 01:59:36 (1768546776) [ 255.695318] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 01:59:40 (1768546780) [ 259.602825] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 01:59:44 (1768546784) [ 264.278424] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 01:59:49 (1768546789) [ 268.734240] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 01:59:53 (1768546793) [ 273.202702] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 01:59:58 (1768546798) [ 278.780387] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 02:00:03 (1768546803) [ 282.737631] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 02:00:07 (1768546807) [ 287.694267] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 02:00:12 (1768546812) [ 291.931123] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 02:00:17 (1768546817) [ 296.128566] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 02:00:21 (1768546821) [ 300.247461] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 02:00:25 (1768546825) [ 304.504581] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 02:00:29 (1768546829) [ 309.005680] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 02:00:34 (1768546834) [ 312.454313] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 02:00:37 (1768546837) [ 316.538266] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 02:00:41 (1768546841) [ 316.933358] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 458.947236] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 02:03:04 (1768546984) [ 463.891949] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 02:03:09 (1768546989) [ 468.282356] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 02:03:13 (1768546993) [ 471.823078] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 02:03:17 (1768546997) [ 475.299605] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 02:03:20 (1768547000) [ 478.978398] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 02:03:24 (1768547004) [ 480.071941] Lustre: DEBUG MARKER: chmod [ 483.293968] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 02:03:28 (1768547008) [ 1100.588694] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 02:13:45 (1768547625) [ 1101.279231] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 24s idle [ 1101.301271] Lustre: Skipped 1 previous similar message [ 1224.160469] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: disconnect after 22s idle [ 1224.171572] Lustre: Skipped 3 previous similar messages [ 1287.860764] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 02:16:52 (1768547812) [ 1408.898187] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 02:18:53 (1768547933) [ 1542.916764] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 02:21:07 (1768548067) [ 1582.559869] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: disconnect after 20s idle [ 1584.509851] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 02:21:49 (1768548109) [ 1592.338909] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 02:21:56 (1768548116) [ 1593.908381] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.053435] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.147186] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.301488] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.424176] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.569765] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.693490] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.791052] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.919780] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1594.996385] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.080824] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.185226] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.267971] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.338320] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.450679] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.536924] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.615336] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.689657] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.752925] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.847773] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1595.942785] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.011841] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.135405] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.239166] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.318725] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.455208] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.560170] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.614784] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.695081] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.779048] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.852785] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.911336] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1596.954409] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.028218] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.116965] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.220873] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.321850] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.398726] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.444880] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.508792] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.598162] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.668731] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.748198] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.820732] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.909211] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1597.986750] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.060116] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.171495] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.244768] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.315974] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.392713] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.467289] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.544506] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.602174] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.690667] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.763796] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.886782] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1598.978376] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.055633] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.166647] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.311803] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.393255] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.464283] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.538895] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.649679] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.775850] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.877725] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1599.978315] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.078714] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.142733] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.210402] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.270943] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.349888] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.440428] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.509976] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.598555] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.662529] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.776208] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1600.922168] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.037663] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.128261] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.216138] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.310610] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.414510] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.550154] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.665620] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.793838] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.890440] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1601.960622] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.103946] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.206271] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.342115] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.441588] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.583651] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.780983] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1602.883732] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.027775] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.124920] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.237097] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.325150] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.408570] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.500597] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.577894] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.653584] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.736892] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.832582] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.902720] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1603.990317] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.070079] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.153220] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.213052] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.275835] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.352611] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.437423] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.532314] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.607188] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.713498] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.875644] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1604.999819] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.104291] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.176357] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.241088] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.300224] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.375834] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.480880] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.549047] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.619231] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.701621] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.781302] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.873429] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1605.958449] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.049273] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.146416] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.233950] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.294835] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.374516] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.440819] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.524905] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.598305] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.681423] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.745439] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.827889] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.898103] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1606.962351] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.065631] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.148805] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.217961] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.287569] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.359273] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.443507] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.514140] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.604437] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.691830] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.764492] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.843232] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1607.951584] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.060812] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.141798] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.162804] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 20s idle [ 1608.241736] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.365545] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.446560] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.533681] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.606147] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.688682] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.762519] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.844125] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.914987] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1608.987395] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.046584] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.125473] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.195687] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.265593] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.334400] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.407763] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.495957] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.568842] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.632915] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.720796] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.810627] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.888139] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1609.960441] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.031461] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.108432] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.161969] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.246244] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.309101] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.390564] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.459771] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.537307] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.613757] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.695565] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.765834] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.835262] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.916234] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1610.993289] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.066041] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.119703] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.186905] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.253948] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.324447] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.403803] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.466286] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.534624] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.651402] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.734873] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.808969] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.903223] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1611.994844] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1612.072803] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1612.168394] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1612.239406] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1612.321848] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1612.447470] rw_seq_cst_vs_d (32326): drop_caches: 3 [ 1621.139219] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 02:22:25 (1768548145) [ 1621.833376] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1621.961373] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.050653] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.168779] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.226259] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.387148] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.417696] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.509442] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.537262] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.630708] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.752527] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1622.826301] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.046043] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.097543] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.337865] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.431154] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.563439] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1623.698661] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.091467] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.223644] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.416126] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.658170] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.732850] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1624.908952] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.040599] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.101969] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.255753] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.440421] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.594282] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.658516] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.837237] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1625.903403] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.056455] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.120718] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.296251] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.535300] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.681438] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.756950] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.886412] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1626.956834] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.164237] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.281759] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.340782] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.494577] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.630907] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.704739] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.753580] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1627.943936] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.009816] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.088987] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.186036] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.302170] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.338667] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.452426] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.648576] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.729094] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.760148] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.798409] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1628.976270] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.155892] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.205300] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.385397] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.484425] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.561207] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.614757] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1629.703193] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.052548] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.188141] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.353197] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.461284] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.570766] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1630.908950] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.201639] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.279614] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.344765] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.573863] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.677046] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.745931] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.846764] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1631.921026] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.117172] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.181264] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.383836] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.569782] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.686088] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.770516] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.829081] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1632.916739] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.014623] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.093828] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.150289] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.200162] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.272556] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.331739] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.398237] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.444645] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.551117] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.734554] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.765580] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: disconnect after 21s idle [ 1633.778117] Lustre: Skipped 1 previous similar message [ 1633.871294] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1633.978930] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.039351] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.178541] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.237529] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.322483] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.366546] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.471205] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.557534] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.598120] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.636334] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.731284] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1634.970548] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.078793] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.144607] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.208642] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.267967] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.327768] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.378148] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.428590] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.482741] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.549971] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.721574] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.936873] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1635.992542] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.329158] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.485275] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.533806] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.664310] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.877904] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.944571] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1636.995374] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.057513] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.283249] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.416573] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.543931] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.677972] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.793930] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.925324] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1637.980857] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1638.141453] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1638.365877] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1638.453038] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1638.754291] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.010531] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.088187] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.150179] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.197607] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.285457] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.373256] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.579265] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.628818] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.745825] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1639.877584] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.017603] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.136317] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.268865] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.351798] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.399467] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.523702] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.569571] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.647635] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.730764] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1640.906059] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1641.155671] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1641.298984] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1641.486798] rw_seq_cst_vs_d (32920): drop_caches: 3 [ 1650.298562] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 02:22:54 (1768548174) [ 1657.461228] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 02:23:02 (1768548182) [ 1665.231893] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 02:23:09 (1768548189) [ 1695.613806] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 02:23:40 (1768548220) [ 1696.092046] bash (35408): drop_caches: 3 [ 1699.292117] bash (35408): drop_caches: 3 [ 1702.440854] bash (35408): drop_caches: 3 [ 1705.617234] bash (35408): drop_caches: 3 [ 1708.787269] bash (35408): drop_caches: 3 [ 1711.976121] bash (35408): drop_caches: 3 [ 1715.163747] bash (35408): drop_caches: 3 [ 1718.348228] bash (35408): drop_caches: 3 [ 1721.558550] bash (35408): drop_caches: 3 [ 1725.058561] bash (35408): drop_caches: 3 [ 1728.281558] bash (35408): drop_caches: 3 [ 1731.539944] bash (35408): drop_caches: 3 [ 1734.669187] bash (35408): drop_caches: 3 [ 1737.794136] bash (35408): drop_caches: 3 [ 1741.022571] bash (35408): drop_caches: 3 [ 1744.219848] bash (35408): drop_caches: 3 [ 1749.967570] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 02:24:33 (1768548273) [ 1761.457807] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 02:24:45 (1768548285) [ 1790.705667] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 02:25:15 (1768548315) [ 1800.795602] Lustre: DEBUG MARKER: loop 5 [ 1802.726261] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 21s idle [ 1802.736733] Lustre: Skipped 1 previous similar message [ 1806.976230] Lustre: DEBUG MARKER: loop 10 [ 1811.851289] Lustre: DEBUG MARKER: loop 15 [ 1817.597946] Lustre: DEBUG MARKER: loop 20 [ 1826.584751] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 02:25:51 (1768548351) [ 1833.685287] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 02:25:58 (1768548358) [ 1841.101369] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 02:26:05 (1768548365) [ 1848.800373] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 21s idle [ 1848.809624] Lustre: Skipped 1 previous similar message [ 1910.191800] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 02:27:14 (1768548434) [ 1918.222194] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 02:27:22 (1768548442) [ 1925.966400] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 02:27:30 (1768548450) [ 1934.421364] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 02:27:38 (1768548458) [ 1942.259403] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 02:27:46 (1768548466) [ 1950.308956] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 02:27:54 (1768548474) [ 1959.461301] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 02:28:04 (1768548484) [ 1968.077581] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1969.513528] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 02:28:14 (1768548494) [ 1977.977972] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 02:28:22 (1768548502) [ 1978.596595] Lustre: *** cfs_fail_loc=314, val=0*** [ 1979.615386] Lustre: *** cfs_fail_loc=314, val=0*** [ 1979.617273] Lustre: Skipped 2 previous similar messages [ 1981.920909] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 20s idle [ 1981.927738] Lustre: Skipped 3 previous similar messages [ 1985.727285] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 02:28:30 (1768548510) [ 1994.769540] Lustre: *** cfs_fail_loc=314, val=0*** [ 1997.305606] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1997.332839] LustreError: lustre-OST0000-osc-ffff9c6251fd9800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1997.347811] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2001.778209] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 02:28:46 (1768548526) [ 2002.057815] LustreError: 46923:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2005.095310] LustreError: 46923:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2011.267354] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 02:28:55 (1768548535) [ 2019.535157] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 02:29:03 (1768548543) [ 2027.731744] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2029.909971] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 02:29:14 (1768548554) [ 2032.067566] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2034.046834] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 02:29:18 (1768548558) [ 2038.266193] Lustre: lustre-MDT0000-mdc-ffff9c6247dba000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2048.508361] LustreError: MGC192.168.201.133@tcp: Connection to MGS (at 192.168.201.133@tcp) was lost; in progress operations using this service will fail [ 2048.558305] Lustre: Evicted from MGS (at 192.168.201.133@tcp) after server handle changed from 0x688bba94d8cbdd26 to 0x688bba94d8dd5593 [ 2048.570402] Lustre: MGC192.168.201.133@tcp: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2051.000580] Lustre: lustre-MDT0000-mdc-ffff9c6251fd9800: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2083.443221] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 02:30:07 (1768548607) [ 2089.439370] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: disconnect after 24s idle [ 2089.445509] Lustre: Skipped 1 previous similar message [ 2133.694601] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 02:30:58 (1768548658) [ 2158.930189] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 02:31:22 (1768548682) [ 2214.349469] Lustre: lustre-OST0001-osc-ffff9c6251fd9800: Connection to lustre-OST0001 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2214.378678] Lustre: Skipped 1 previous similar message [ 2214.388682] LustreError: lustre-OST0001-osc-ffff9c6247dba000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2214.395834] LustreError: lustre-OST0001-osc-ffff9c6251fd9800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2214.396441] Lustre: lustre-OST0001-osc-ffff9c6247dba000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2214.414191] Lustre: Skipped 1 previous similar message [ 2229.738501] Lustre: lustre-OST0000-osc-ffff9c6247dba000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2229.762050] Lustre: Skipped 1 previous similar message [ 2229.798520] LustreError: lustre-OST0000-osc-ffff9c6247dba000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2229.843334] Lustre: lustre-OST0000-osc-ffff9c6247dba000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [ 2229.863856] Lustre: Skipped 1 previous similar message [ 2235.871338] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 21s idle [ 2235.892017] Lustre: Skipped 1 previous similar message [ 2248.009978] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2249.376793] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid in FULL state after 0 sec [ 2253.170766] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2254.472163] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid in IDLE state after 0 sec [ 2259.410286] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2260.861480] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid in IDLE state after 0 sec [ 2265.066241] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2266.557309] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid in IDLE state after 0 sec [ 2279.207888] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2281.630135] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c6247dba000.ost_server_uuid in IDLE state after 0 sec [ 2285.688312] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid 50 [ 2287.113311] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c6247dba000.ost_server_uuid in IDLE state after 0 sec [ 2288.648602] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 02:33:33 (1768548813) [ 2291.317260] Lustre: DEBUG MARKER: Race attempt 0 [ 2294.146264] Lustre: DEBUG MARKER: Wait for 57403 57432 for 60 sec... [ 2361.689965] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 02:34:46 (1768548886) [ 2369.606538] Lustre: DEBUG MARKER: start test - cycle (0) [ 2392.517415] Lustre: DEBUG MARKER: start test - cycle (1) [ 2418.498131] Lustre: DEBUG MARKER: start test - cycle (2) [ 2444.909961] Lustre: DEBUG MARKER: start test - cycle (3) [ 2469.346977] Lustre: DEBUG MARKER: start test - cycle (4) [ 2495.106852] Lustre: DEBUG MARKER: start test - cycle (5) [ 2496.996852] Lustre: lustre-OST0001-osc-ffff9c6251fd9800: disconnect after 21s idle [ 2497.002767] Lustre: Skipped 3 previous similar messages [ 2520.057622] Lustre: DEBUG MARKER: start test - cycle (6) [ 2546.014869] Lustre: DEBUG MARKER: start test - cycle (7) [ 2566.883990] Lustre: DEBUG MARKER: start test - cycle (8) [ 2586.999842] Lustre: DEBUG MARKER: start test - cycle (9) [ 2607.417900] Lustre: DEBUG MARKER: start test - cycle (10) [ 2633.412396] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 02:39:18 (1768549158) [ 2699.491617] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 02:40:23 (1768549223) [ 2706.765183] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 02:40:31 (1768549231) [ 2714.874758] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 02:40:39 (1768549239) [ 2721.995931] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 02:40:46 (1768549246) [ 2722.350794] Lustre: *** cfs_fail_loc=411, val=0*** [ 2728.935228] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2730.675064] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 02:40:55 (1768549255) [ 2747.819369] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 02:41:12 (1768549272) [ 2765.131365] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 02:41:30 (1768549290) [ 2780.485831] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 02:41:45 (1768549305) [ 2796.711977] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 02:42:01 (1768549321) [ 2808.591878] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 02:42:13 (1768549333) [ 2821.267687] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 02:42:25 (1768549345) [ 2834.426232] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 02:42:39 (1768549359) [ 2846.080217] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 02:42:50 (1768549370) [ 2859.723202] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 02:43:04 (1768549384) [ 2875.707682] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 02:43:19 (1768549399) [ 2889.687850] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 02:43:34 (1768549414) [ 2904.597979] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 02:43:49 (1768549429) [ 3524.063320] Lustre: lustre-OST0000-osc-ffff9c6251fd9800: disconnect after 25s idle [ 3524.073073] Lustre: Skipped 13 previous similar messages [ 3981.906795] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 03:01:46 (1768550506) [ 3994.980938] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 03:01:59 (1768550519) [ 4009.480626] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 03:02:13 (1768550533) [ 4024.056696] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 03:02:28 (1768550548) [ 4038.679354] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 03:02:43 (1768550563) [ 4054.143205] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 03:02:58 (1768550578) [ 4068.695476] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 03:03:12 (1768550592) [ 4082.358821] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 03:03:27 (1768550607) [ 4097.129278] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 03:03:41 (1768550621) [ 4205.745302] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 03:05:30 (1768550730) [ 4222.288387] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 03:05:46 (1768550746) [ 4236.950983] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 03:06:01 (1768550761) [ 4250.768117] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 03:06:15 (1768550775) [ 4251.106727] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 20s idle [ 4251.117404] Lustre: Skipped 4 previous similar messages [ 4265.371529] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 03:06:30 (1768550790) [ 4279.858912] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 03:06:44 (1768550804) [ 4295.770057] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 03:07:00 (1768550820) [ 4310.908280] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 03:07:15 (1768550835) [ 4325.372840] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 03:07:29 (1768550849) [ 4456.419727] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 03:09:40 (1768550980) [ 4906.463475] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 22s idle [ 4906.472143] Lustre: Skipped 12 previous similar messages [ 5633.507546] Lustre: lustre-OST0001-osc-ffff9c6251fd9800: disconnect after 20s idle [ 5633.522441] Lustre: Skipped 1 previous similar message [ 5715.901245] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 03:30:40 (1768552240) [ 5734.810861] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 03:30:59 (1768552259) [ 5752.063694] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 03:31:15 (1768552275) [ 5767.693536] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 03:31:31 (1768552291) [ 5782.386500] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 03:31:46 (1768552306) [ 5797.501639] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 03:32:01 (1768552321) [ 5811.635679] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 03:32:16 (1768552336) [ 5825.122868] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 03:32:29 (1768552349) [ 5839.629128] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 03:32:44 (1768552364) [ 5853.662895] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 03:32:57 (1768552377) [ 6016.127464] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 03:35:40 (1768552540) [ 6030.476569] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 03:35:55 (1768552555) [ 6045.141482] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 03:36:09 (1768552569) [ 6060.463331] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 03:36:25 (1768552585) [ 6077.169897] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 03:36:41 (1768552601) [ 6092.117466] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 03:36:56 (1768552616) [ 6106.481210] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 03:37:11 (1768552631) [ 6121.420357] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 03:37:26 (1768552646) [ 6136.358169] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 03:37:40 (1768552660) [ 6253.023431] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 20s idle [ 6253.036450] Lustre: Skipped 16 previous similar messages [ 6857.183377] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 23s idle [ 6857.194982] Lustre: Skipped 12 previous similar messages [ 7467.021876] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 03:59:51 (1768553991) [ 7471.583613] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 24s idle [ 7471.588788] Lustre: Skipped 12 previous similar messages [ 7481.051525] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 04:00:05 (1768554005) [ 7495.206315] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 04:00:19 (1768554019) [ 7511.435165] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 04:00:35 (1768554035) [ 7528.218793] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 04:00:52 (1768554052) [ 7545.324433] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 04:01:09 (1768554069) [ 7561.429553] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 04:01:25 (1768554085) [ 7574.792986] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 04:01:39 (1768554099) [ 7588.112668] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 04:01:52 (1768554112) [ 7603.461726] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 04:02:07 (1768554127) [ 7617.360872] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 04:02:21 (1768554141) [ 7633.427893] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 04:02:37 (1768554157) [ 7649.696281] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 04:02:53 (1768554173) [ 7662.910886] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 04:03:07 (1768554187) [ 7676.939171] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 04:03:21 (1768554201) [ 7690.547977] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 04:03:35 (1768554215) [ 7708.666397] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 04:03:53 (1768554233) [ 7709.025351] LustreError: 11572:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7711.111115] LustreError: 11572:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7721.976047] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 04:04:06 (1768554246) [ 7732.105542] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 04:04:16 (1768554256) [ 7732.571294] LustreError: 238437:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7736.639132] LustreError: 238437:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7736.685360] LustreError: 238437:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7740.759252] LustreError: 238437:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7740.814684] LustreError: 238443:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7744.887434] LustreError: 238443:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7752.313554] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 04:04:36 (1768554276) [ 7764.943407] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 04:04:49 (1768554289) [ 7773.265563] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 04:04:57 (1768554297) [ 7782.813772] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 04:05:07 (1768554307) [ 7816.417248] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 04:05:41 (1768554341) [ 7831.279181] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 04:05:55 (1768554355) [ 7844.337860] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 04:06:08 (1768554368) [ 7863.326935] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 04:06:28 (1768554388) [ 7878.190662] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 04:06:42 (1768554402) [ 7899.559400] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 04:07:03 (1768554423) [ 7920.153525] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 04:07:24 (1768554444) [ 7943.156889] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 04:07:47 (1768554467) [ 7966.595887] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 04:08:10 (1768554490) [ 8075.550789] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 04:10:00 (1768554600) [ 8083.576583] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 8089.406446] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 04:10:14 (1768554614) [ 8096.391468] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 04:10:20 (1768554620) [ 8104.134761] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 04:10:28 (1768554628) [ 8111.138848] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 04:10:35 (1768554635) [ 8148.552684] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 04:11:13 (1768554673) [ 8167.908719] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 20s idle [ 8167.916873] Lustre: Skipped 12 previous similar messages [ 8210.078833] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 04:12:14 (1768554734) [ 8217.701943] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 04:12:22 (1768554742) [ 8225.020462] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 04:12:29 (1768554749) [ 8228.283122] LustreError: lustre-MDT0000-mdc-ffff9c6251fd9800: operation ldlm_enqueue to node 192.168.201.133@tcp failed: rc = -35 [ 8236.594732] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 04:12:40 (1768554760) [ 8237.257949] LustreError: 2411:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8239.263858] LustreError: 2411:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8249.877786] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 04:12:54 (1768554774) [ 8425.297511] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 04:15:49 (1768554949) [ 8436.159746] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 04:16:00 (1768554960) [ 8449.780079] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 04:16:14 (1768554974) [ 8466.493585] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 04:16:31 (1768554991) [ 8481.349261] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 04:16:46 (1768555006) [ 8503.163591] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 04:17:08 (1768555028) [ 8527.142893] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 04:17:31 (1768555051) [ 8539.611956] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 04:17:44 (1768555064) [ 8551.216983] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 04:17:55 (1768555075) [ 8573.747494] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 04:18:18 (1768555098) [ 8629.524305] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 04:19:14 (1768555154) [ 8763.824376] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 04:21:28 (1768555288) [ 8787.423384] Lustre: lustre-OST0001-osc-ffff9c6247dba000: disconnect after 20s idle [ 8787.433594] Lustre: Skipped 12 previous similar messages [ 8903.105942] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 04:23:47 (1768555427) [ 9262.243624] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 04:29:46 (1768555786) [ 9307.428430] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 04:30:32 (1768555832) [ 9353.249379] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 04:31:17 (1768555877) [ 9391.583272] Lustre: lustre-OST0000-osc-ffff9c6247dba000: disconnect after 21s idle [ 9391.596217] Lustre: Skipped 15 previous similar messages [ 9395.794893] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 04:31:59 (1768555919) [ 9478.829843] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 04:33:23 (1768556003) [ 9553.321321] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 04:34:37 (1768556077) [ 9675.320639] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 04:36:40 (1768556200) [ 9679.864764] LustreError: 283584:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6247dba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9679.877705] LustreError: 283584:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9679.936663] Lustre: Unmounted lustre-client [ 9683.092942] LustreError: 283598:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6251fd9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9683.103598] LustreError: 283598:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9683.112721] LustreError: 283598:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9683.120660] LustreError: 283598:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9683.198028] Lustre: Unmounted lustre-client [ 9799.714294] Lustre: Mounted lustre-client [ 9802.500558] Lustre: Mounted lustre-client [ 9805.406828] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9895.155656] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 04:40:19 (1768556419) [ 9911.111472] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 04:40:35 (1768556435) [ 9922.500189] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 04:40:46 (1768556446) [ 9979.347067] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 04:41:43 (1768556503) [10033.119982] Lustre: lustre-OST0001-osc-ffff9c6245bd8000: disconnect after 21s idle [10033.123026] Lustre: Skipped 11 previous similar messages [10052.429594] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 04:42:57 (1768556577) [10064.768431] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 04:43:09 (1768556589) [10182.108534] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 04:45:06 (1768556706) [10222.387199] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 04:45:47 (1768556747) [10274.683367] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 04:46:39 (1768556799) [10286.625916] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 04:46:50 (1768556810) [10310.275605] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 04:47:14 (1768556834) [10322.651720] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 04:47:26 (1768556846) [10324.879925] LustreError: 300647:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x17f:0x0]: rc = -5 [10324.905088] LustreError: 300647:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10325.632926] LustreError: 300657:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x17f:0x0]: rc = -5 [10325.653246] LustreError: 300657:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10325.679715] LustreError: 300657:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10325.702164] LustreError: 300657:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 1 previous similar message [10327.051151] LustreError: 300667:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x18b:0x0]: rc = -5 [10327.057601] LustreError: 300667:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10327.063505] LustreError: 300667:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10327.067330] LustreError: 300667:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 1 previous similar message [10329.480717] LustreError: 300699:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x33:0x0]: rc = -5 [10329.499745] LustreError: 300699:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [10329.536748] LustreError: 300699:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10329.544749] LustreError: 300699:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 5 previous similar messages [10433.972259] LustreError: 300730:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x3f:0x0]: rc = -5 [10433.983866] LustreError: 300730:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [10433.990627] LustreError: 300730:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10434.003210] LustreError: 300730:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 5 previous similar messages [10434.045489] LustreError: lustre-MDT0001-mdc-ffff9c6245bd8000: operation mds_getattr_lock to node 192.168.201.133@tcp failed: rc = -107 [10434.052990] Lustre: lustre-MDT0001-mdc-ffff9c6245bd8000: Connection to lustre-MDT0001 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [10434.100656] LustreError: lustre-MDT0001-mdc-ffff9c6245bd8000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [10434.112953] LustreError: 300728:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9c6245bd8000: inode [0x240000bd0:0x3b:0x0] mdc close failed: rc = -108 [10434.136326] Lustre: lustre-MDT0001-mdc-ffff9c6245bd8000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [10442.300995] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 04:49:26 (1768556966) [10450.876302] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 04:49:35 (1768556975) [10686.046170] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 04:53:30 (1768557210) [10687.788985] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10690.003790] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 04:53:34 (1768557214) [10903.614763] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 04:57:08 (1768557428) [10910.162802] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 04:57:15 (1768557435) [10918.880272] Lustre: lustre-OST0000-osc-ffff9c626f3fe000: disconnect after 20s idle [10918.890031] Lustre: Skipped 7 previous similar messages [11037.258101] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 04:59:21 (1768557561) [11051.080817] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 04:59:35 (1768557575) [11064.723412] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 04:59:49 (1768557589) [11251.210894] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 05:02:55 (1768557775) [11438.729728] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 05:06:03 (1768557963) [11446.218991] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 05:06:10 (1768557970) [11465.524920] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 05:06:29 (1768557989) [11465.737086] Lustre: DEBUG MARKER: write [11465.812861] LustreError: 290595:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [11467.840835] Lustre: DEBUG MARKER: kill 330760 [11467.858155] LustreError: 330760:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [11470.831181] LustreError: 290595:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [11473.903188] LustreError: 330760:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [11481.402094] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 05:06:46 (1768558006) [11484.130989] LustreError: 331373:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [11494.135185] LustreError: 331373:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [11502.315311] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 05:07:06 (1768558026) [11502.774837] LustreError: 331961:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [11504.863536] LustreError: 331961:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [11517.126820] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 05:07:21 (1768558041) [11519.078857] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [11521.405333] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 05:07:25 (1768558045) [11529.135519] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 05:07:33 (1768558053) [11536.140534] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 05:07:40 (1768558060) [11543.497433] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 05:07:48 (1768558068) [11550.582396] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 05:07:55 (1768558075) [11557.980425] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 05:08:02 (1768558082) [11565.902458] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 05:08:10 (1768558090) [11575.083773] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 05:08:19 (1768558099) [11584.645347] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 05:08:28 (1768558108) [11586.411871] Lustre: *** cfs_fail_loc=415, val=0*** [11599.620959] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 05:08:44 (1768558124) [11629.849922] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 05:09:14 (1768558154) [11630.246598] LustreError: 286008:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [11630.268160] LustreError: 286008:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [11635.279132] LustreError: 286008:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11645.464922] LustreError: 285315:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11645.483485] LustreError: 285315:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 6 previous similar messages [11658.650649] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 05:09:43 (1768558183) [11666.592267] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 05:09:51 (1768558191) [11673.976733] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 05:09:58 (1768558198) [11681.320450] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 05:10:06 (1768558206) [11690.831273] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 05:10:15 (1768558215) [11704.594975] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 05:10:29 (1768558229) [11705.135393] LustreError: 342707:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11705.142418] LustreError: 342707:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [11709.207210] LustreError: 342707:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [11709.212601] LustreError: 342707:0:(osc_request.c:2944:osc_build_rpc()) Skipped 2 previous similar messages [11716.927782] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 05:10:40 (1768558240) [11721.785364] LustreError: 343417:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6245bd8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11721.795965] LustreError: 343417:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11721.818233] LustreError: 343417:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11721.823897] LustreError: 343417:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11721.892167] Lustre: Unmounted lustre-client [11724.350196] LustreError: 343438:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c626f3fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11724.361389] LustreError: 343438:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11724.372545] LustreError: 343438:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11724.377554] LustreError: 343438:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11724.425860] Lustre: Unmounted lustre-client [11725.764459] Lustre: DEBUG MARKER: Iteration 1 [11726.189692] LustreError: 343601:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11726.198321] LustreError: 343602:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11726.210100] LustreError: 343601:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11726.506284] Lustre: Mounted lustre-client [11727.913392] LustreError: 343713:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62477b2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11727.931476] LustreError: 343713:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11727.960964] LustreError: 343713:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11727.964792] LustreError: 343713:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11728.039588] Lustre: Unmounted lustre-client [11728.044517] Lustre: Skipped 1 previous similar message [11730.737785] Key type lgssc unregistered [11730.999150] LNet: 343961:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11731.007644] LNetError: 343961:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11731.017574] LNet: Removed LNI 192.168.201.33@tcp [11731.795316] Key type .llcrypt unregistered [11731.800280] Key type ._llcrypt unregistered [11732.706289] Key type ._llcrypt registered [11732.707819] Key type .llcrypt registered [11733.459617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11733.473612] alg: No test for adler32 (adler32-zlib) [11734.964364] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11735.866647] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11737.625449] Key type lgssc registered [11739.663599] Lustre: Echo OBD driver; http://www.lustre.org/ [11753.927781] Lustre: DEBUG MARKER: Iteration 2 [11754.459681] LustreError: 344791:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11754.464228] LustreError: 344792:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11754.480758] LustreError: 344791:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11756.925710] Lustre: Mounted lustre-client [11758.405708] LustreError: 344900:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8f5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11758.437294] LustreError: 344900:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11758.490852] Lustre: Unmounted lustre-client [11761.229870] Key type lgssc unregistered [11761.478837] LNet: 345150:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11761.497516] LNetError: 345150:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11761.533633] LNet: Removed LNI 192.168.201.33@tcp [11762.108336] Key type .llcrypt unregistered [11762.120788] Key type ._llcrypt unregistered [11762.784667] Key type ._llcrypt registered [11762.794372] Key type .llcrypt registered [11763.228209] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11763.242414] alg: No test for adler32 (adler32-zlib) [11764.276461] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11764.473829] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11766.191493] Key type lgssc registered [11767.703458] Lustre: Echo OBD driver; http://www.lustre.org/ [11779.255713] Lustre: DEBUG MARKER: Iteration 3 [11779.630497] LustreError: 345981:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11779.632086] LustreError: 345983:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11779.644951] LustreError: 345981:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11780.935914] Lustre: Mounted lustre-client [11782.429923] LustreError: 346092:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62517a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11782.450642] LustreError: 346092:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11782.525143] Lustre: Unmounted lustre-client [11784.986345] Key type lgssc unregistered [11785.261298] LNet: 346340:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11785.268771] LNetError: 346340:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11785.294804] LNet: Removed LNI 192.168.201.33@tcp [11785.938273] Key type .llcrypt unregistered [11785.940129] Key type ._llcrypt unregistered [11786.711204] Key type ._llcrypt registered [11786.712978] Key type .llcrypt registered [11787.017875] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11787.034426] alg: No test for adler32 (adler32-zlib) [11788.354522] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11788.616155] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11790.311214] Key type lgssc registered [11791.640595] Lustre: Echo OBD driver; http://www.lustre.org/ [11803.949482] Lustre: DEBUG MARKER: Iteration 4 [11804.278524] LustreError: 347169:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11804.282405] LustreError: 347179:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11804.296436] LustreError: 347169:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11805.575557] Lustre: Mounted lustre-client [11807.450683] LustreError: 347279:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6245bde000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11807.478918] LustreError: 347279:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11807.552658] Lustre: Unmounted lustre-client [11810.007493] Key type lgssc unregistered [11810.297575] LNet: 347525:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11810.307616] LNetError: 347525:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11810.325291] LNet: Removed LNI 192.168.201.33@tcp [11810.845163] Key type .llcrypt unregistered [11810.850428] Key type ._llcrypt unregistered [11811.519659] Key type ._llcrypt registered [11811.521078] Key type .llcrypt registered [11811.889653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11811.900985] alg: No test for adler32 (adler32-zlib) [11812.893663] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11813.051790] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11814.703284] Key type lgssc registered [11816.123615] Lustre: Echo OBD driver; http://www.lustre.org/ [11825.787667] Lustre: DEBUG MARKER: Iteration 5 [11826.204779] LustreError: 348355:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11826.208579] LustreError: 348361:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11826.225591] LustreError: 348355:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [11827.587416] Lustre: Mounted lustre-client [11829.025488] LustreError: 348468:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8f3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11829.066595] LustreError: 348468:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11829.144954] Lustre: Unmounted lustre-client [11831.622501] Key type lgssc unregistered [11831.853022] LNet: 348718:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11831.859558] LNetError: 348718:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11831.883544] LNet: Removed LNI 192.168.201.33@tcp [11832.351153] Key type .llcrypt unregistered [11832.352757] Key type ._llcrypt unregistered [11833.035933] Key type ._llcrypt registered [11833.038822] Key type .llcrypt registered [11833.348602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11833.363639] alg: No test for adler32 (adler32-zlib) [11834.400120] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11834.549557] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11836.193041] Key type lgssc registered [11837.207904] Lustre: Echo OBD driver; http://www.lustre.org/ [11847.190377] Lustre: DEBUG MARKER: Iteration 6 [11847.485616] LustreError: 349549:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11847.491111] LustreError: 349550:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11847.499994] LustreError: 349549:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11849.871955] Lustre: Mounted lustre-client [11851.117074] LustreError: 349665:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6246909800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11851.135484] LustreError: 349665:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11851.192244] Lustre: Unmounted lustre-client [11853.832886] Key type lgssc unregistered [11854.121234] LNet: 349905:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11854.129894] LNetError: 349905:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11854.150774] LNet: Removed LNI 192.168.201.33@tcp [11854.797180] Key type .llcrypt unregistered [11854.799964] Key type ._llcrypt unregistered [11855.490706] Key type ._llcrypt registered [11855.496584] Key type .llcrypt registered [11855.925712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11855.937939] alg: No test for adler32 (adler32-zlib) [11857.066461] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11857.315538] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11859.031242] Key type lgssc registered [11860.127604] Lustre: Echo OBD driver; http://www.lustre.org/ [11870.867164] Lustre: DEBUG MARKER: Iteration 7 [11871.286871] LustreError: 350735:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11871.288052] LustreError: 350736:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11871.307399] LustreError: 350735:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11872.659526] Lustre: Mounted lustre-client [11873.864775] LustreError: 350845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa12000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11873.881651] LustreError: 350845:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11873.921320] Lustre: Unmounted lustre-client [11876.228645] Key type lgssc unregistered [11876.492545] LNet: 351095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11876.507107] LNetError: 351095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11876.523689] LNet: Removed LNI 192.168.201.33@tcp [11877.119300] Key type .llcrypt unregistered [11877.123628] Key type ._llcrypt unregistered [11877.807533] Key type ._llcrypt registered [11877.810805] Key type .llcrypt registered [11878.289127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11878.306036] alg: No test for adler32 (adler32-zlib) [11879.402447] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11879.639592] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11881.295150] Key type lgssc registered [11883.110619] Lustre: Echo OBD driver; http://www.lustre.org/ [11895.110109] Lustre: DEBUG MARKER: Iteration 8 [11895.407095] LustreError: 351926:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11895.407448] LustreError: 351927:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11895.421199] LustreError: 351926:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11896.710093] Lustre: Mounted lustre-client [11897.946070] LustreError: 352048:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6251ea3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11897.964413] LustreError: 352048:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11897.969151] LustreError: 352048:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11898.022694] Lustre: Unmounted lustre-client [11900.207289] Key type lgssc unregistered [11900.482728] LNet: 352291:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11900.491673] LNetError: 352291:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11900.507175] LNet: Removed LNI 192.168.201.33@tcp [11901.174250] Key type .llcrypt unregistered [11901.176146] Key type ._llcrypt unregistered [11901.719474] Key type ._llcrypt registered [11901.723677] Key type .llcrypt registered [11902.196189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11902.215063] alg: No test for adler32 (adler32-zlib) [11903.234230] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11903.390028] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11905.047197] Key type lgssc registered [11905.968498] Lustre: Echo OBD driver; http://www.lustre.org/ [11915.279439] Lustre: DEBUG MARKER: Iteration 9 [11915.694969] LustreError: 353120:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11915.695345] LustreError: 353121:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11915.712570] LustreError: 353120:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [11917.053329] Lustre: Mounted lustre-client [11918.411552] LustreError: 353227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa13000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11918.432882] LustreError: 353227:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11918.482387] Lustre: Unmounted lustre-client [11920.906863] Key type lgssc unregistered [11921.117835] LNet: 353476:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11921.128635] LNetError: 353476:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11921.143253] LNet: Removed LNI 192.168.201.33@tcp [11921.758303] Key type .llcrypt unregistered [11921.762335] Key type ._llcrypt unregistered [11922.382603] Key type ._llcrypt registered [11922.384285] Key type .llcrypt registered [11922.793836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11922.806253] alg: No test for adler32 (adler32-zlib) [11923.932409] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11924.145032] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11925.775175] Key type lgssc registered [11926.808926] Lustre: Echo OBD driver; http://www.lustre.org/ [11936.140378] Lustre: DEBUG MARKER: Iteration 10 [11936.428871] LustreError: 354305:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11936.430771] LustreError: 354307:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11936.440392] LustreError: 354305:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11937.685589] Lustre: Mounted lustre-client [11938.659115] LustreError: 354415:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62598d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11938.685787] LustreError: 354415:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11938.741472] Lustre: Unmounted lustre-client [11941.042984] Key type lgssc unregistered [11941.328367] LNet: 354664:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11941.337681] LNetError: 354664:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11941.351076] LNet: Removed LNI 192.168.201.33@tcp [11941.958131] Key type .llcrypt unregistered [11941.960906] Key type ._llcrypt unregistered [11942.654286] Key type ._llcrypt registered [11942.657337] Key type .llcrypt registered [11943.088274] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11943.106583] alg: No test for adler32 (adler32-zlib) [11944.113945] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11944.280742] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11945.927190] Key type lgssc registered [11947.081462] Lustre: Echo OBD driver; http://www.lustre.org/ [11958.230695] Lustre: DEBUG MARKER: Iteration 11 [11958.639728] LustreError: 355494:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11958.641132] LustreError: 355495:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11958.662126] LustreError: 355494:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11959.970385] Lustre: Mounted lustre-client [11961.088133] LustreError: 355607:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62470a7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11961.110942] LustreError: 355607:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11961.176209] Lustre: Unmounted lustre-client [11963.232678] Key type lgssc unregistered [11963.443646] LNet: 355851:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11963.452630] LNetError: 355851:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11963.469826] LNet: Removed LNI 192.168.201.33@tcp [11963.950313] Key type .llcrypt unregistered [11963.952729] Key type ._llcrypt unregistered [11964.665909] Key type ._llcrypt registered [11964.675274] Key type .llcrypt registered [11965.046577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11965.057512] alg: No test for adler32 (adler32-zlib) [11966.081920] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11966.320730] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11968.031189] Key type lgssc registered [11968.994161] Lustre: Echo OBD driver; http://www.lustre.org/ [11979.544344] Lustre: DEBUG MARKER: Iteration 12 [11979.804232] LustreError: 356682:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11979.804754] LustreError: 356683:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11979.820660] LustreError: 356682:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11981.138285] Lustre: Mounted lustre-client [11982.549429] LustreError: 356793:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6246e9e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11982.572667] LustreError: 356793:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11982.639145] Lustre: Unmounted lustre-client [11985.031795] Key type lgssc unregistered [11985.225247] LNet: 357042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11985.231974] LNetError: 357042:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11986.283721] LNet: Removed LNI 192.168.201.33@tcp [11986.911161] Key type .llcrypt unregistered [11986.913583] Key type ._llcrypt unregistered [11987.408897] Key type ._llcrypt registered [11987.410545] Key type .llcrypt registered [11987.801505] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11987.812662] alg: No test for adler32 (adler32-zlib) [11988.813440] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [11988.988467] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [11990.655242] Key type lgssc registered [11991.759510] Lustre: Echo OBD driver; http://www.lustre.org/ [12004.829565] Lustre: DEBUG MARKER: Iteration 13 [12005.307858] LustreError: 357872:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12005.310098] LustreError: 357874:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12005.324374] LustreError: 357872:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12006.633409] Lustre: Mounted lustre-client [12008.594803] LustreError: 357982:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627a2ea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12008.636954] LustreError: 357982:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12008.714716] Lustre: Unmounted lustre-client [12012.395031] Key type lgssc unregistered [12012.743333] LNet: 358232:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12012.759771] LNetError: 358232:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12012.787384] LNet: Removed LNI 192.168.201.33@tcp [12013.816952] Key type .llcrypt unregistered [12013.820855] Key type ._llcrypt unregistered [12015.093288] Key type ._llcrypt registered [12015.095114] Key type .llcrypt registered [12015.783208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12015.793349] alg: No test for adler32 (adler32-zlib) [12017.069768] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12017.546302] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12019.408436] Key type lgssc registered [12020.711083] Lustre: Echo OBD driver; http://www.lustre.org/ [12033.101356] Lustre: DEBUG MARKER: Iteration 14 [12033.524485] LustreError: 359062:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12033.527495] LustreError: 359064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12033.543894] LustreError: 359062:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12034.972558] Lustre: Mounted lustre-client [12034.980071] Lustre: Skipped 1 previous similar message [12036.399831] LustreError: 359177:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6246e9e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12036.426321] LustreError: 359177:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12036.508666] Lustre: Unmounted lustre-client [12039.002879] Key type lgssc unregistered [12039.320720] LNet: 359424:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12039.337200] LNetError: 359424:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12039.375270] LNet: Removed LNI 192.168.201.33@tcp [12040.093678] Key type .llcrypt unregistered [12040.100670] Key type ._llcrypt unregistered [12040.872290] Key type ._llcrypt registered [12040.875309] Key type .llcrypt registered [12041.512822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12041.533709] alg: No test for adler32 (adler32-zlib) [12042.649637] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12042.933784] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12044.649136] Key type lgssc registered [12045.720441] Lustre: Echo OBD driver; http://www.lustre.org/ [12057.155597] Lustre: DEBUG MARKER: Iteration 15 [12057.462781] LustreError: 360253:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12057.463483] LustreError: 360256:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12057.486587] LustreError: 360253:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12058.779152] Lustre: Mounted lustre-client [12059.974070] LustreError: 360363:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8c5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12059.986742] LustreError: 360363:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12060.075154] Lustre: Unmounted lustre-client [12062.492217] Key type lgssc unregistered [12062.722167] LNet: 360612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12062.739868] LNetError: 360612:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12062.778051] LNet: Removed LNI 192.168.201.33@tcp [12063.455323] Key type .llcrypt unregistered [12063.456758] Key type ._llcrypt unregistered [12064.286322] Key type ._llcrypt registered [12064.291744] Key type .llcrypt registered [12064.651106] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12064.666423] alg: No test for adler32 (adler32-zlib) [12065.710567] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12066.017544] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12067.735269] Key type lgssc registered [12069.268825] Lustre: Echo OBD driver; http://www.lustre.org/ [12081.487190] Lustre: DEBUG MARKER: Iteration 16 [12082.099429] LustreError: 361444:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12082.100277] LustreError: 361443:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12082.111485] LustreError: 361444:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12084.440309] Lustre: Mounted lustre-client [12085.889538] LustreError: 361555:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6259846800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12085.908400] LustreError: 361555:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12085.919776] LustreError: 361557:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12085.981436] Lustre: Unmounted lustre-client [12088.739867] Key type lgssc unregistered [12088.983866] LNet: 361798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12088.993194] LNetError: 361798:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12089.021880] LNet: Removed LNI 192.168.201.33@tcp [12089.595441] Key type .llcrypt unregistered [12089.596903] Key type ._llcrypt unregistered [12090.319789] Key type ._llcrypt registered [12090.327376] Key type .llcrypt registered [12090.859779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12090.876864] alg: No test for adler32 (adler32-zlib) [12091.968466] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12092.206581] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12093.863479] Key type lgssc registered [12095.331872] Lustre: Echo OBD driver; http://www.lustre.org/ [12107.395097] Lustre: DEBUG MARKER: Iteration 17 [12107.730729] LustreError: 362627:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12107.730919] LustreError: 362630:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12107.753200] LustreError: 362627:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [12109.075592] Lustre: Mounted lustre-client [12110.340598] LustreError: 362739:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62517a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12110.354779] LustreError: 362739:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12110.413142] Lustre: Unmounted lustre-client [12112.712685] Key type lgssc unregistered [12112.922164] LNet: 362988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12112.928719] LNetError: 362988:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12112.946410] LNet: Removed LNI 192.168.201.33@tcp [12113.520172] Key type .llcrypt unregistered [12113.522256] Key type ._llcrypt unregistered [12114.273001] Key type ._llcrypt registered [12114.292664] Key type .llcrypt registered [12114.877334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12114.912978] alg: No test for adler32 (adler32-zlib) [12115.954808] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12116.247985] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12117.999215] Key type lgssc registered [12119.194952] Lustre: Echo OBD driver; http://www.lustre.org/ [12130.652672] Lustre: DEBUG MARKER: Iteration 18 [12131.021192] LustreError: 363820:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12131.021590] LustreError: 363821:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12131.043226] LustreError: 363820:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12132.397566] Lustre: Mounted lustre-client [12133.863818] LustreError: 363926:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8c3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12133.881987] LustreError: 363926:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12133.982979] Lustre: Unmounted lustre-client [12136.312508] Key type lgssc unregistered [12136.542784] LNet: 364177:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12136.550314] LNetError: 364177:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12136.567589] LNet: Removed LNI 192.168.201.33@tcp [12137.094222] Key type .llcrypt unregistered [12137.098507] Key type ._llcrypt unregistered [12137.867315] Key type ._llcrypt registered [12137.872668] Key type .llcrypt registered [12138.248223] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12138.258107] alg: No test for adler32 (adler32-zlib) [12139.416730] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12139.778107] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12141.551914] Key type lgssc registered [12143.195254] Lustre: Echo OBD driver; http://www.lustre.org/ [12157.123700] Lustre: DEBUG MARKER: Iteration 19 [12157.701665] LustreError: 365008:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12157.702241] LustreError: 365009:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12157.722917] LustreError: 365008:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4980 [12159.158524] Lustre: Mounted lustre-client [12160.967615] LustreError: 365114:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa14800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12160.999153] LustreError: 365114:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12161.102547] Lustre: Unmounted lustre-client [12164.529307] Key type lgssc unregistered [12164.817050] LNet: 365362:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12164.835179] LNetError: 365362:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12164.863497] LNet: Removed LNI 192.168.201.33@tcp [12165.864155] Key type .llcrypt unregistered [12165.866333] Key type ._llcrypt unregistered [12167.122480] Key type ._llcrypt registered [12167.140726] Key type .llcrypt registered [12167.883544] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12167.902925] alg: No test for adler32 (adler32-zlib) [12169.068234] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12169.340149] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12171.047322] Key type lgssc registered [12172.430177] Lustre: Echo OBD driver; http://www.lustre.org/ [12186.171896] Lustre: DEBUG MARKER: Iteration 20 [12186.733684] LustreError: 366197:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12186.734957] LustreError: 366192:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12186.756726] LustreError: 366197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12188.223114] Lustre: Mounted lustre-client [12189.380977] LustreError: 366304:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6252de5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12189.396754] LustreError: 366304:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12189.441920] Lustre: Unmounted lustre-client [12192.112674] Key type lgssc unregistered [12192.359671] LNet: 366553:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12192.363899] LNetError: 366553:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12192.386502] LNet: Removed LNI 192.168.201.33@tcp [12193.027322] Key type .llcrypt unregistered [12193.029713] Key type ._llcrypt unregistered [12193.754714] Key type ._llcrypt registered [12193.760273] Key type .llcrypt registered [12194.378407] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12194.387649] alg: No test for adler32 (adler32-zlib) [12195.375213] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12195.528133] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12197.167250] Key type lgssc registered [12198.540560] Lustre: Echo OBD driver; http://www.lustre.org/ [12211.887808] Lustre: DEBUG MARKER: Iteration 21 [12212.273644] LustreError: 367385:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12212.274211] LustreError: 367384:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12212.288270] LustreError: 367385:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [12213.756624] Lustre: Mounted lustre-client [12215.001750] LustreError: 367501:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12215.023989] LustreError: 367501:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12215.126362] Lustre: Unmounted lustre-client [12217.791091] Key type lgssc unregistered [12218.144733] LNet: 367747:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12218.167940] LNetError: 367747:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12218.189160] LNet: Removed LNI 192.168.201.33@tcp [12218.805304] Key type .llcrypt unregistered [12218.811462] Key type ._llcrypt unregistered [12219.900705] Key type ._llcrypt registered [12219.910396] Key type .llcrypt registered [12220.831495] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12220.860855] alg: No test for adler32 (adler32-zlib) [12221.969373] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12222.357489] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12224.175172] Key type lgssc registered [12225.651577] Lustre: Echo OBD driver; http://www.lustre.org/ [12239.931794] Lustre: DEBUG MARKER: Iteration 22 [12240.470172] LustreError: 368577:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12240.477136] LustreError: 368578:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12240.488183] LustreError: 368577:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12241.952231] Lustre: Mounted lustre-client [12243.782882] LustreError: 368687:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6247f4e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12243.822559] LustreError: 368687:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12243.927454] Lustre: Unmounted lustre-client [12248.274791] Key type lgssc unregistered [12248.740823] LNet: 368937:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12248.750474] LNetError: 368937:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12248.778913] LNet: Removed LNI 192.168.201.33@tcp [12249.418186] Key type .llcrypt unregistered [12249.419936] Key type ._llcrypt unregistered [12250.394436] Key type ._llcrypt registered [12250.403986] Key type .llcrypt registered [12250.989337] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12251.000825] alg: No test for adler32 (adler32-zlib) [12252.202852] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12252.408927] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12254.056721] Key type lgssc registered [12255.641353] Lustre: Echo OBD driver; http://www.lustre.org/ [12268.982544] Lustre: DEBUG MARKER: Iteration 23 [12269.375979] LustreError: 369768:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12269.383367] LustreError: 369769:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12269.400958] LustreError: 369768:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12270.765880] Lustre: Mounted lustre-client [12273.347369] LustreError: 369883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6245bdf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12273.415615] LustreError: 369883:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12273.547700] Lustre: Unmounted lustre-client [12276.984518] Key type lgssc unregistered [12277.488787] LNet: 370128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12277.497974] LNetError: 370128:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12277.511729] LNet: Removed LNI 192.168.201.33@tcp [12278.634200] Key type .llcrypt unregistered [12278.644420] Key type ._llcrypt unregistered [12280.595791] Key type ._llcrypt registered [12280.609578] Key type .llcrypt registered [12281.254512] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12281.289370] alg: No test for adler32 (adler32-zlib) [12282.626787] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12283.007730] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12284.823644] Key type lgssc registered [12286.586662] Lustre: Echo OBD driver; http://www.lustre.org/ [12300.304325] Lustre: DEBUG MARKER: Iteration 24 [12301.021407] LustreError: 370959:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12301.023302] LustreError: 370960:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12301.036980] LustreError: 370959:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12302.413338] Lustre: Mounted lustre-client [12304.027152] LustreError: 371070:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6259844800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12304.040534] LustreError: 371070:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12304.049857] LustreError: 371074:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12304.131603] Lustre: Unmounted lustre-client [12307.272987] Key type lgssc unregistered [12307.712773] LNet: 371316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12307.742023] LNetError: 371316:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12307.782661] LNet: Removed LNI 192.168.201.33@tcp [12308.815931] Key type .llcrypt unregistered [12308.818276] Key type ._llcrypt unregistered [12310.105099] Key type ._llcrypt registered [12310.123559] Key type .llcrypt registered [12311.003397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12311.025137] alg: No test for adler32 (adler32-zlib) [12312.407099] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12312.736736] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12314.481191] Key type lgssc registered [12316.981300] Lustre: Echo OBD driver; http://www.lustre.org/ [12331.491189] Lustre: DEBUG MARKER: Iteration 25 [12331.834250] LustreError: 372145:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12331.834503] LustreError: 372147:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12331.845418] LustreError: 372145:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12333.291876] Lustre: Mounted lustre-client [12333.298788] Lustre: Skipped 1 previous similar message [12334.993375] LustreError: 372254:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa11800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12335.014250] LustreError: 372254:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12335.103004] Lustre: Unmounted lustre-client [12338.216858] Key type lgssc unregistered [12338.471388] LNet: 372502:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12338.477467] LNetError: 372502:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12338.501057] LNet: Removed LNI 192.168.201.33@tcp [12339.250477] Key type .llcrypt unregistered [12339.253758] Key type ._llcrypt unregistered [12340.364266] Key type ._llcrypt registered [12340.366229] Key type .llcrypt registered [12340.811171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12340.824476] alg: No test for adler32 (adler32-zlib) [12342.057855] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12342.420693] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12344.082268] Key type lgssc registered [12345.184036] Lustre: Echo OBD driver; http://www.lustre.org/ [12356.021174] Lustre: DEBUG MARKER: Iteration 26 [12356.464503] LustreError: 373333:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12356.468850] LustreError: 373334:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12356.486131] LustreError: 373333:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12358.874314] Lustre: Mounted lustre-client [12360.422617] LustreError: 373441:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6251c33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12360.455739] LustreError: 373441:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12360.505368] Lustre: Unmounted lustre-client [12363.168644] Key type lgssc unregistered [12363.461410] LNet: 373690:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12363.467304] LNetError: 373690:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12363.498524] LNet: Removed LNI 192.168.201.33@tcp [12364.140413] Key type .llcrypt unregistered [12364.143501] Key type ._llcrypt unregistered [12364.875937] Key type ._llcrypt registered [12364.877420] Key type .llcrypt registered [12365.365420] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12365.378233] alg: No test for adler32 (adler32-zlib) [12366.439991] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12366.765951] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12368.535322] Key type lgssc registered [12370.267509] Lustre: Echo OBD driver; http://www.lustre.org/ [12382.553649] Lustre: DEBUG MARKER: Iteration 27 [12383.050088] LustreError: 374516:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12383.059719] LustreError: 374518:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12383.066811] LustreError: 374516:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [12384.387305] Lustre: Mounted lustre-client [12386.134896] LustreError: 374635:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6247f4a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12386.151921] LustreError: 374635:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12386.205207] Lustre: Unmounted lustre-client [12389.476320] Key type lgssc unregistered [12389.729383] LNet: 374880:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12389.742358] LNetError: 374880:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12389.761077] LNet: Removed LNI 192.168.201.33@tcp [12390.736169] Key type .llcrypt unregistered [12390.738894] Key type ._llcrypt unregistered [12391.861443] Key type ._llcrypt registered [12391.863312] Key type .llcrypt registered [12392.652690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12392.662623] alg: No test for adler32 (adler32-zlib) [12393.801359] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12394.095431] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12395.807362] Key type lgssc registered [12397.223623] Lustre: Echo OBD driver; http://www.lustre.org/ [12409.123764] Lustre: DEBUG MARKER: Iteration 28 [12409.468835] LustreError: 375711:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12409.471488] LustreError: 375713:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12409.491947] LustreError: 375711:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12410.807386] Lustre: Mounted lustre-client [12412.400906] LustreError: 375823:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625252f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12412.422191] LustreError: 375823:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [12412.438283] LustreError: 375823:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12412.495599] Lustre: Unmounted lustre-client [12415.191940] Key type lgssc unregistered [12415.440217] LNet: 376068:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12415.449173] LNetError: 376068:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12416.509657] LNet: Removed LNI 192.168.201.33@tcp [12417.364135] Key type .llcrypt unregistered [12417.368778] Key type ._llcrypt unregistered [12418.243384] Key type ._llcrypt registered [12418.252933] Key type .llcrypt registered [12419.015773] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12419.032178] alg: No test for adler32 (adler32-zlib) [12420.221635] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12420.457918] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12422.168726] Key type lgssc registered [12423.604456] Lustre: Echo OBD driver; http://www.lustre.org/ [12438.220605] Lustre: DEBUG MARKER: Iteration 29 [12438.835578] LustreError: 376902:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12438.837880] LustreError: 376901:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12438.850066] LustreError: 376902:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12441.274576] Lustre: Mounted lustre-client [12442.723133] LustreError: 377017:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6258291800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12442.748424] LustreError: 377017:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12442.814740] Lustre: Unmounted lustre-client [12445.952890] Key type lgssc unregistered [12446.408776] LNet: 377261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12446.426600] LNetError: 377261:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12446.466130] LNet: Removed LNI 192.168.201.33@tcp [12447.298304] Key type .llcrypt unregistered [12447.299894] Key type ._llcrypt unregistered [12448.100219] Key type ._llcrypt registered [12448.117828] Key type .llcrypt registered [12448.865954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12448.880516] alg: No test for adler32 (adler32-zlib) [12449.917492] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12450.117660] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12451.799260] Key type lgssc registered [12453.802750] Lustre: Echo OBD driver; http://www.lustre.org/ [12467.058973] Lustre: DEBUG MARKER: Iteration 30 [12467.319050] LustreError: 378094:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12467.323251] LustreError: 378093:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12467.347099] LustreError: 378094:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12468.783722] Lustre: Mounted lustre-client [12470.806896] LustreError: 378202:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62517a1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12470.847520] LustreError: 378202:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12470.940599] Lustre: Unmounted lustre-client [12473.668682] Key type lgssc unregistered [12473.947763] LNet: 378451:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12473.958365] LNetError: 378451:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12473.989174] LNet: Removed LNI 192.168.201.33@tcp [12474.847179] Key type .llcrypt unregistered [12474.849536] Key type ._llcrypt unregistered [12475.872624] Key type ._llcrypt registered [12475.903919] Key type .llcrypt registered [12476.473728] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12476.496466] alg: No test for adler32 (adler32-zlib) [12477.762318] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12478.100735] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12479.903425] Key type lgssc registered [12481.321512] Lustre: Echo OBD driver; http://www.lustre.org/ [12494.125940] Lustre: DEBUG MARKER: Iteration 31 [12494.654465] LustreError: 379282:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12494.657413] LustreError: 379280:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12494.674525] LustreError: 379282:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12496.127135] Lustre: Mounted lustre-client [12498.835513] LustreError: 379390:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6250f90000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12498.874457] LustreError: 379390:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12498.970087] Lustre: Unmounted lustre-client [12502.999587] Key type lgssc unregistered [12503.421261] LNet: 379640:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12503.435403] LNetError: 379640:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12504.490804] LNet: Removed LNI 192.168.201.33@tcp [12505.742888] Key type .llcrypt unregistered [12505.750723] Key type ._llcrypt unregistered [12507.137466] Key type ._llcrypt registered [12507.143031] Key type .llcrypt registered [12507.647908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12507.667491] alg: No test for adler32 (adler32-zlib) [12508.958600] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12509.309920] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12511.071402] Key type lgssc registered [12512.544323] Lustre: Echo OBD driver; http://www.lustre.org/ [12526.693750] Lustre: DEBUG MARKER: Iteration 32 [12527.228773] LustreError: 380470:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12527.229379] LustreError: 380471:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12527.264900] LustreError: 380470:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4976 [12528.646930] Lustre: Mounted lustre-client [12530.297374] LustreError: 380578:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62532ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12530.325309] LustreError: 380578:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12530.389111] Lustre: Unmounted lustre-client [12533.299807] Key type lgssc unregistered [12533.645628] LNet: 380828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12533.651337] LNetError: 380828:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12533.676321] LNet: Removed LNI 192.168.201.33@tcp [12534.362372] Key type .llcrypt unregistered [12534.371879] Key type ._llcrypt unregistered [12535.568408] Key type ._llcrypt registered [12535.607474] Key type .llcrypt registered [12535.964640] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12535.978253] alg: No test for adler32 (adler32-zlib) [12536.953538] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12537.281104] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12538.983231] Key type lgssc registered [12540.142509] Lustre: Echo OBD driver; http://www.lustre.org/ [12552.687410] Lustre: DEBUG MARKER: Iteration 33 [12553.014607] LustreError: 381658:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12553.015122] LustreError: 381659:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12553.034259] LustreError: 381658:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [12555.480701] Lustre: Mounted lustre-client [12557.161656] LustreError: 381771:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62477b3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12557.202653] LustreError: 381771:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12557.317226] Lustre: Unmounted lustre-client [12560.425246] Key type lgssc unregistered [12560.712947] LNet: 382021:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12560.732137] LNetError: 382021:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12560.747581] LNet: Removed LNI 192.168.201.33@tcp [12561.759300] Key type .llcrypt unregistered [12561.762087] Key type ._llcrypt unregistered [12563.139743] Key type ._llcrypt registered [12563.155835] Key type .llcrypt registered [12563.438807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12563.448545] alg: No test for adler32 (adler32-zlib) [12564.456826] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12564.652942] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12566.303170] Key type lgssc registered [12567.940694] Lustre: Echo OBD driver; http://www.lustre.org/ [12579.936909] Lustre: DEBUG MARKER: Iteration 34 [12580.333499] LustreError: 382854:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12580.336499] LustreError: 382853:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12580.350835] LustreError: 382854:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12582.616498] Lustre: Mounted lustre-client [12583.937242] LustreError: 382960:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8f1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12583.955792] LustreError: 382960:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12584.028509] Lustre: Unmounted lustre-client [12586.284519] Key type lgssc unregistered [12586.495234] LNet: 383208:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12586.504231] LNetError: 383208:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12586.538410] LNet: Removed LNI 192.168.201.33@tcp [12587.144153] Key type .llcrypt unregistered [12587.149798] Key type ._llcrypt unregistered [12587.747173] Key type ._llcrypt registered [12587.750828] Key type .llcrypt registered [12588.100462] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12588.110649] alg: No test for adler32 (adler32-zlib) [12589.150669] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12589.334455] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12590.943193] Key type lgssc registered [12591.979897] Lustre: Echo OBD driver; http://www.lustre.org/ [12603.807309] Lustre: DEBUG MARKER: Iteration 35 [12604.293347] LustreError: 384040:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12604.296357] LustreError: 384039:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12604.321273] LustreError: 384040:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [12605.697049] Lustre: Mounted lustre-client [12607.335738] LustreError: 384153:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6245bdf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12607.379401] LustreError: 384153:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12607.393515] LustreError: 384153:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12607.444560] Lustre: Unmounted lustre-client [12610.184444] Key type lgssc unregistered [12610.417783] LNet: 384396:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12610.428813] LNetError: 384396:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12610.453572] LNet: Removed LNI 192.168.201.33@tcp [12611.217327] Key type .llcrypt unregistered [12611.220384] Key type ._llcrypt unregistered [12612.199664] Key type ._llcrypt registered [12612.203213] Key type .llcrypt registered [12612.649978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12612.665785] alg: No test for adler32 (adler32-zlib) [12613.839658] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12614.068285] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12615.735156] Key type lgssc registered [12617.037362] Lustre: Echo OBD driver; http://www.lustre.org/ [12631.141922] Lustre: DEBUG MARKER: Iteration 36 [12631.518941] LustreError: 385228:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12631.523739] LustreError: 385230:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12631.536778] LustreError: 385228:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [12632.921410] Lustre: Mounted lustre-client [12632.928479] Lustre: Skipped 1 previous similar message [12634.321938] LustreError: 385335:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62477b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12634.344545] LustreError: 385335:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12634.402742] Lustre: Unmounted lustre-client [12637.185717] Key type lgssc unregistered [12637.536645] LNet: 385583:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12637.551684] LNetError: 385583:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12637.574849] LNet: Removed LNI 192.168.201.33@tcp [12638.345256] Key type .llcrypt unregistered [12638.351238] Key type ._llcrypt unregistered [12639.555025] Key type ._llcrypt registered [12639.561133] Key type .llcrypt registered [12640.099401] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12640.111736] alg: No test for adler32 (adler32-zlib) [12641.523552] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12641.957685] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12643.751169] Key type lgssc registered [12644.881335] Lustre: Echo OBD driver; http://www.lustre.org/ [12657.454148] Lustre: DEBUG MARKER: Iteration 37 [12658.044777] LustreError: 386415:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12658.048874] LustreError: 386416:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12658.062281] LustreError: 386415:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12660.581161] Lustre: Mounted lustre-client [12662.306213] LustreError: 386524:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6278326000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12662.331927] LustreError: 386524:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12662.405369] Lustre: Unmounted lustre-client [12665.515100] Key type lgssc unregistered [12665.897955] LNet: 386773:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12665.912917] LNetError: 386773:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12665.938833] LNet: Removed LNI 192.168.201.33@tcp [12667.147141] Key type .llcrypt unregistered [12667.152000] Key type ._llcrypt unregistered [12668.165141] Key type ._llcrypt registered [12668.171532] Key type .llcrypt registered [12668.631111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12668.645728] alg: No test for adler32 (adler32-zlib) [12669.741131] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12669.926741] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12671.591237] Key type lgssc registered [12673.488460] Lustre: Echo OBD driver; http://www.lustre.org/ [12687.862751] Lustre: DEBUG MARKER: Iteration 38 [12688.213272] LustreError: 387604:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12688.213808] LustreError: 387607:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12688.219373] LustreError: 387604:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12689.468519] Lustre: Mounted lustre-client [12690.602144] LustreError: 387712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62532b8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12690.619649] LustreError: 387712:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12690.672622] Lustre: Unmounted lustre-client [12693.247989] Key type lgssc unregistered [12693.499716] LNet: 387961:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12693.504985] LNetError: 387961:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12693.528462] LNet: Removed LNI 192.168.201.33@tcp [12694.112294] Key type .llcrypt unregistered [12694.114278] Key type ._llcrypt unregistered [12694.843043] Key type ._llcrypt registered [12694.844828] Key type .llcrypt registered [12695.151810] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12695.163719] alg: No test for adler32 (adler32-zlib) [12696.170165] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12696.413402] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12698.087197] Key type lgssc registered [12699.192518] Lustre: Echo OBD driver; http://www.lustre.org/ [12710.308389] Lustre: DEBUG MARKER: Iteration 39 [12710.724844] LustreError: 388789:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12710.725782] LustreError: 388791:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12710.739791] LustreError: 388789:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [12713.215176] Lustre: Mounted lustre-client [12714.495142] LustreError: 388895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6247f4f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12714.516625] LustreError: 388895:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12714.598060] Lustre: Unmounted lustre-client [12717.388232] Key type lgssc unregistered [12717.619934] LNet: 389144:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12717.629640] LNetError: 389144:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12717.642768] LNet: Removed LNI 192.168.201.33@tcp [12718.287873] Key type .llcrypt unregistered [12718.291351] Key type ._llcrypt unregistered [12719.175982] Key type ._llcrypt registered [12719.181025] Key type .llcrypt registered [12719.722908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12719.740515] alg: No test for adler32 (adler32-zlib) [12720.948513] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12721.265822] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12723.015204] Key type lgssc registered [12724.205756] Lustre: Echo OBD driver; http://www.lustre.org/ [12737.120833] Lustre: DEBUG MARKER: Iteration 40 [12737.672971] LustreError: 389974:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12737.678983] LustreError: 389976:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12737.691218] LustreError: 389974:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12739.146152] Lustre: Mounted lustre-client [12741.373876] LustreError: 390086:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62477b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12741.415028] LustreError: 390086:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12741.540480] Lustre: Unmounted lustre-client [12744.160728] Key type lgssc unregistered [12744.396674] LNet: 390336:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12744.408359] LNetError: 390336:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12744.442815] LNet: Removed LNI 192.168.201.33@tcp [12745.388293] Key type .llcrypt unregistered [12745.390060] Key type ._llcrypt unregistered [12746.355134] Key type ._llcrypt registered [12746.357792] Key type .llcrypt registered [12746.871806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12746.882393] alg: No test for adler32 (adler32-zlib) [12748.176077] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12748.512564] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12750.199232] Key type lgssc registered [12751.767614] Lustre: Echo OBD driver; http://www.lustre.org/ [12764.913909] Lustre: DEBUG MARKER: Iteration 41 [12765.257576] LustreError: 391167:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12765.258457] LustreError: 391168:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12765.281700] LustreError: 391167:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12767.681370] Lustre: Mounted lustre-client [12769.591463] LustreError: 391281:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6254baa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12769.604117] LustreError: 391281:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12769.616843] LustreError: 391282:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12769.710535] Lustre: Unmounted lustre-client [12773.499916] Key type lgssc unregistered [12773.777367] LNet: 391522:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12773.788486] LNetError: 391522:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12773.812507] LNet: Removed LNI 192.168.201.33@tcp [12774.811256] Key type .llcrypt unregistered [12774.816873] Key type ._llcrypt unregistered [12775.923557] Key type ._llcrypt registered [12775.934230] Key type .llcrypt registered [12776.849622] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12776.864959] alg: No test for adler32 (adler32-zlib) [12778.075625] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12778.387744] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12780.083488] Key type lgssc registered [12781.890505] Lustre: Echo OBD driver; http://www.lustre.org/ [12797.824428] Lustre: DEBUG MARKER: Iteration 42 [12798.184312] LustreError: 392352:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12798.185105] LustreError: 392353:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12798.202924] LustreError: 392352:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12799.478590] Lustre: Mounted lustre-client [12801.214780] LustreError: 392458:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8f0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12801.240684] LustreError: 392458:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12801.329897] Lustre: Unmounted lustre-client [12804.323669] Key type lgssc unregistered [12804.631577] LNet: 392709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12804.634953] LNetError: 392709:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12804.646988] LNet: Removed LNI 192.168.201.33@tcp [12805.383326] Key type .llcrypt unregistered [12805.384850] Key type ._llcrypt unregistered [12806.844665] Key type ._llcrypt registered [12806.846659] Key type .llcrypt registered [12807.008191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12807.018736] alg: No test for adler32 (adler32-zlib) [12808.387842] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12808.641163] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12810.375184] Key type lgssc registered [12811.664157] Lustre: Echo OBD driver; http://www.lustre.org/ [12824.873705] Lustre: DEBUG MARKER: Iteration 43 [12825.443358] LustreError: 393539:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12825.451982] LustreError: 393540:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12825.460282] LustreError: 393539:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [12826.805745] Lustre: Mounted lustre-client [12828.382699] LustreError: 393644:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62802e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12828.415690] LustreError: 393644:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12828.529427] Lustre: Unmounted lustre-client [12830.911777] Key type lgssc unregistered [12831.182296] LNet: 393895:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12831.191524] LNetError: 393895:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12831.206375] LNet: Removed LNI 192.168.201.33@tcp [12831.754674] Key type .llcrypt unregistered [12831.761272] Key type ._llcrypt unregistered [12832.379466] Key type ._llcrypt registered [12832.384859] Key type .llcrypt registered [12832.947523] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12832.967672] alg: No test for adler32 (adler32-zlib) [12834.032788] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12834.234223] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12835.935314] Key type lgssc registered [12837.381672] Lustre: Echo OBD driver; http://www.lustre.org/ [12850.885535] Lustre: DEBUG MARKER: Iteration 44 [12851.410593] LustreError: 394727:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12851.412560] LustreError: 394728:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12851.430643] LustreError: 394727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12852.808366] Lustre: Mounted lustre-client [12854.590668] LustreError: 394835:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62470a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12854.631403] LustreError: 394835:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12854.735758] Lustre: Unmounted lustre-client [12857.972362] Key type lgssc unregistered [12858.311828] LNet: 395085:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12858.317856] LNetError: 395085:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12858.340425] LNet: Removed LNI 192.168.201.33@tcp [12859.291494] Key type .llcrypt unregistered [12859.295482] Key type ._llcrypt unregistered [12860.281759] Key type ._llcrypt registered [12860.285924] Key type .llcrypt registered [12860.828983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12860.849333] alg: No test for adler32 (adler32-zlib) [12862.174867] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12862.306854] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12863.967227] Key type lgssc registered [12865.988402] Lustre: Echo OBD driver; http://www.lustre.org/ [12879.744635] Lustre: DEBUG MARKER: Iteration 45 [12880.151208] LustreError: 395911:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12880.152370] LustreError: 395914:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12880.164712] LustreError: 395911:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12881.488746] Lustre: Mounted lustre-client [12883.132884] LustreError: 396033:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa14000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12883.149018] LustreError: 396033:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12883.155771] LustreError: 396032:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12883.227239] Lustre: Unmounted lustre-client [12886.486488] Key type lgssc unregistered [12886.721670] LNet: 396275:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12886.741377] LNetError: 396275:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12887.786908] LNet: Removed LNI 192.168.201.33@tcp [12888.746146] Key type .llcrypt unregistered [12888.751903] Key type ._llcrypt unregistered [12890.149438] Key type ._llcrypt registered [12890.154503] Key type .llcrypt registered [12890.645859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12890.670876] alg: No test for adler32 (adler32-zlib) [12891.843369] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12892.193461] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12894.039180] Key type lgssc registered [12895.363268] Lustre: Echo OBD driver; http://www.lustre.org/ [12907.919743] Lustre: DEBUG MARKER: Iteration 46 [12908.554951] LustreError: 397106:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12908.559787] LustreError: 397107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12908.591896] LustreError: 397106:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [12909.949492] Lustre: Mounted lustre-client [12911.559587] LustreError: 397210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12911.574906] LustreError: 397210:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12911.631041] Lustre: Unmounted lustre-client [12914.269653] Key type lgssc unregistered [12914.481950] LNet: 397459:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12914.494427] LNetError: 397459:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12914.517597] LNet: Removed LNI 192.168.201.33@tcp [12915.334349] Key type .llcrypt unregistered [12915.336842] Key type ._llcrypt unregistered [12916.331852] Key type ._llcrypt registered [12916.357321] Key type .llcrypt registered [12917.032868] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12917.071976] alg: No test for adler32 (adler32-zlib) [12918.238628] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12918.578368] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12920.271200] Key type lgssc registered [12921.924318] Lustre: Echo OBD driver; http://www.lustre.org/ [12935.930648] Lustre: DEBUG MARKER: Iteration 47 [12936.482313] LustreError: 398291:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12936.488241] LustreError: 398292:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12936.506425] LustreError: 398291:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12938.865315] Lustre: Mounted lustre-client [12938.868381] Lustre: Skipped 1 previous similar message [12940.508436] LustreError: 398399:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c6259a84800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12940.545380] LustreError: 398399:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12940.635230] Lustre: Unmounted lustre-client [12944.033399] Key type lgssc unregistered [12944.405792] LNet: 398648:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12944.411332] LNetError: 398648:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12944.440478] LNet: Removed LNI 192.168.201.33@tcp [12945.306362] Key type .llcrypt unregistered [12945.309504] Key type ._llcrypt unregistered [12946.494601] Key type ._llcrypt registered [12946.515060] Key type .llcrypt registered [12947.021877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12947.046731] alg: No test for adler32 (adler32-zlib) [12948.143713] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12948.403291] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12950.103520] Key type lgssc registered [12951.339347] Lustre: Echo OBD driver; http://www.lustre.org/ [12963.680770] Lustre: DEBUG MARKER: Iteration 48 [12963.997610] LustreError: 399478:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12963.998362] LustreError: 399479:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12964.020210] LustreError: 399478:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12966.443616] Lustre: Mounted lustre-client [12968.030712] LustreError: 399590:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa10000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12968.074677] LustreError: 399590:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12968.181448] Lustre: Unmounted lustre-client [12971.599915] Key type lgssc unregistered [12971.988481] LNet: 399838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12972.003948] LNetError: 399838:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12972.022589] LNet: Removed LNI 192.168.201.33@tcp [12972.699173] Key type .llcrypt unregistered [12972.700920] Key type ._llcrypt unregistered [12973.509947] Key type ._llcrypt registered [12973.514662] Key type .llcrypt registered [12973.964452] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12973.988156] alg: No test for adler32 (adler32-zlib) [12975.039193] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [12975.267066] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [12976.943785] Key type lgssc registered [12978.596152] Lustre: Echo OBD driver; http://www.lustre.org/ [12993.309892] Lustre: DEBUG MARKER: Iteration 49 [12993.982732] LustreError: 400669:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12993.983110] LustreError: 400670:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12993.999448] LustreError: 400669:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12995.411948] Lustre: Mounted lustre-client [12997.510835] LustreError: 400786:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c625a8c2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12997.542460] LustreError: 400786:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12997.659177] Lustre: Unmounted lustre-client [13001.630269] Key type lgssc unregistered [13001.970265] LNet: 401031:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13001.990100] LNetError: 401031:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13002.008367] LNet: Removed LNI 192.168.201.33@tcp [13002.874675] Key type .llcrypt unregistered [13002.877871] Key type ._llcrypt unregistered [13003.947740] Key type ._llcrypt registered [13003.979043] Key type .llcrypt registered [13005.092664] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13005.116251] alg: No test for adler32 (adler32-zlib) [13006.697394] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [13007.118276] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [13008.879995] Key type lgssc registered [13010.218217] Lustre: Echo OBD driver; http://www.lustre.org/ [13023.136041] Lustre: DEBUG MARKER: Iteration 50 [13023.834644] LustreError: 401857:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [13023.834877] LustreError: 401862:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [13023.853311] LustreError: 401857:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [13025.194624] Lustre: Mounted lustre-client [13027.176375] LustreError: 401969:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627fa16000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13027.211733] LustreError: 401969:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [13027.262593] Lustre: Unmounted lustre-client [13030.600338] Key type lgssc unregistered [13030.833061] LNet: 402219:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13030.841596] LNetError: 402219:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13030.864288] LNet: Removed LNI 192.168.201.33@tcp [13031.623163] Key type .llcrypt unregistered [13031.625272] Key type ._llcrypt unregistered [13032.586258] Key type ._llcrypt registered [13032.602437] Key type .llcrypt registered [13032.948741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13032.971035] alg: No test for adler32 (adler32-zlib) [13034.089532] Lustre: Lustre: Build Version: 2.17.50_30_gbb2e37c [13034.420848] LNet: Added LNI 192.168.201.33@tcp [8/256/0/180] [13036.111396] Key type lgssc registered [13037.403224] Lustre: Echo OBD driver; http://www.lustre.org/ [13051.085309] Lustre: Mounted lustre-client [13057.965868] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 05:33:02 (1768559582) [13075.423492] Lustre: 403578:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559585/real 1768559585] req@ffff9c626f154a80 x1854469109197952/t0(0) o36->lustre-MDT0000-mdc-ffff9c627d4e9000@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1768559601 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13075.453192] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13075.509206] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13091.809070] Lustre: 403578:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559601/real 1768559601] req@ffff9c626f154a80 x1854469109197952/t0(0) o36->lustre-MDT0000-mdc-ffff9c627d4e9000@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1768559617 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13091.857616] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13091.937806] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13107.170375] Lustre: 403578:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559617/real 1768559617] req@ffff9c626f154a80 x1854469109197952/t0(0) o36->lustre-MDT0000-mdc-ffff9c627d4e9000@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1768559633 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13107.235855] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13107.308758] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13123.551178] Lustre: 403578:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559633/real 1768559633] req@ffff9c626f154a80 x1854469109197952/t0(0) o36->lustre-MDT0000-mdc-ffff9c627d4e9000@192.168.201.133@tcp:12/10 lens 496/440 e 0 to 1 dl 1768559649 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13123.619639] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection to lustre-MDT0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13123.667681] Lustre: lustre-MDT0000-mdc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13130.711114] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 05:34:15 (1768559655) [13144.765634] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 05:34:29 (1768559669) [13156.883722] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 05:34:41 (1768559681) [13164.615751] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 05:34:49 (1768559689) [13186.444505] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 05:35:11 (1768559711) [13222.253283] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 05:35:46 (1768559746) [13230.017317] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 05:35:54 (1768559754) [13230.558075] LustreError: 408360:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [13232.640086] LustreError: 408360:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [13240.524229] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 05:36:04 (1768559764) [13264.799174] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559774/real 1768559774] req@ffff9c62781ee680 x1854469110253312/t0(0) o4->lustre-OST0000-osc-ffff9c627d4e9000@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768559790 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13264.828521] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13264.868658] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13280.031917] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559790/real 1768559790] req@ffff9c62781ee680 x1854469110253312/t0(0) o4->lustre-OST0000-osc-ffff9c627d4e9000@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768559806 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13280.058533] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 8 previous similar messages [13280.066480] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13280.110739] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13296.607285] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559806/real 1768559806] req@ffff9c62781ee680 x1854469110253312/t0(0) o4->lustre-OST0000-osc-ffff9c627d4e9000@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768559822 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13296.607595] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13296.646552] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [13296.717472] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13329.375208] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768559839/real 1768559839] req@ffff9c62781ee680 x1854469110253312/t0(0) o4->lustre-OST0000-osc-ffff9c627d4e9000@192.168.201.133@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768559855 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13329.419690] Lustre: 402410:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [13329.433570] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection to lustre-OST0000 (at 192.168.201.133@tcp) was lost; in progress operations using this service will wait for recovery to complete [13329.464737] Lustre: Skipped 1 previous similar message [13329.499640] Lustre: lustre-OST0000-osc-ffff9c627d4e9000: Connection restored to 192.168.201.133@tcp (at 192.168.201.133@tcp) [13329.527574] Lustre: Skipped 1 previous similar message [13344.849532] Lustre: DEBUG MARKER: oleg133-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c62470a3000.ost_server_uuid 50 [13346.714912] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c62470a3000.ost_server_uuid in IDLE state after 0 sec [13348.719738] Lustre: DEBUG MARKER: cleanup: ====================================================== [13351.189792] Lustre: DEBUG MARKER: == sanityn test complete, duration 13169 sec ============= 05:37:55 (1768559875) [13352.978844] Lustre: DEBUG MARKER: === sanityn: start cleanup 05:37:57 (1768559877) === [13653.890365] LustreError: 410450:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c62470a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13653.904276] LustreError: 410450:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [13653.961102] Lustre: Unmounted lustre-client [13658.314823] Lustre: DEBUG MARKER: === sanityn: finish cleanup 05:43:02 (1768560182) === [13661.015751] LustreError: 410755:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c627d4e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13661.027441] LustreError: 410755:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [13661.043453] LustreError: 410755:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [13661.048881] LustreError: 410755:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [13661.108909] Lustre: Unmounted lustre-client [13716.773884] Key type lgssc unregistered [13717.067190] LNet: 411442:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13717.074116] LNetError: 411442:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13717.096609] LNet: Removed LNI 192.168.201.33@tcp [13717.786150] Key type .llcrypt unregistered [13717.789410] Key type ._llcrypt unregistered